{"level":"warn","ts":"2026-02-25T10:02:28.3275Z","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-25T10:02:28.327577Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.36: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://162.253.55.36:2380","--initial-cluster=instance=https://162.253.55.36:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.36:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.36: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-25T10:02:28.32764Z","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-25T10:02:28.327651Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-02-25T10:02:28.327667Z","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-25T10:02:28.328343Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"]} {"level":"info","ts":"2026-02-25T10:02:28.328417Z","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":8,"max-cpu-available":8,"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://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.36: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-25T10:02:28.333064Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.441658ms"} {"level":"info","ts":"2026-02-25T10:02:28.339146Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cb89ec34a9687f0b","cluster-id":"d8e0d5e222e856c9"} {"level":"info","ts":"2026-02-25T10:02:28.339238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T10:02:28.339276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 0"} {"level":"info","ts":"2026-02-25T10:02:28.3393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cb89ec34a9687f0b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T10:02:28.339314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 1"} {"level":"info","ts":"2026-02-25T10:02:28.339374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"warn","ts":"2026-02-25T10:02:28.347214Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T10:02:28.348878Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T10:02:28.350399Z","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-25T10:02:28.353581Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cb89ec34a9687f0b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T10:02:28.353675Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cb89ec34a9687f0b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T10:02:28.353691Z","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-25T10:02:28.353804Z","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-25T10:02:28.353828Z","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-25T10:02:28.355889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"info","ts":"2026-02-25T10:02:28.356602Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","added-peer-id":"cb89ec34a9687f0b","added-peer-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-02-25T10:02:28.358456Z","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-25T10:02:28.358512Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-02-25T10:02:28.358549Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-02-25T10:02:28.358919Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cb89ec34a9687f0b","initial-advertise-peer-urls":["https://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T10:02:28.358994Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T10:02:29.140105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T10:02:29.140187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T10:02:29.140221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgPreVoteResp from cb89ec34a9687f0b at term 1"} {"level":"info","ts":"2026-02-25T10:02:29.140324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became candidate at term 2"} {"level":"info","ts":"2026-02-25T10:02:29.140348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgVoteResp from cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-02-25T10:02:29.140366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became leader at term 2"} {"level":"info","ts":"2026-02-25T10:02:29.140379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cb89ec34a9687f0b elected leader cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-02-25T10:02:29.141338Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:02:29.142257Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cb89ec34a9687f0b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.36:2379]}","request-path":"/0/members/cb89ec34a9687f0b/attributes","cluster-id":"d8e0d5e222e856c9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T10:02:29.142396Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T10:02:29.142516Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T10:02:29.142544Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T10:02:29.142581Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T10:02:29.142792Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:02:29.142891Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:02:29.143208Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:02:29.146269Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T10:02:29.146391Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.36:2379"} {"level":"info","ts":"2026-02-25T10:03:01.603679Z","caller":"traceutil/trace.go:171","msg":"trace[276964513] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"515.988409ms","start":"2026-02-25T10:03:01.087659Z","end":"2026-02-25T10:03:01.603648Z","steps":["trace[276964513] 'process raft request' (duration: 515.802565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:01.604595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:01.087638Z","time spent":"516.147883ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:03:01.645733Z","caller":"traceutil/trace.go:171","msg":"trace[1824646761] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"309.159158ms","start":"2026-02-25T10:03:01.336549Z","end":"2026-02-25T10:03:01.645708Z","steps":["trace[1824646761] 'process raft request' (duration: 308.897802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:01.645861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:01.336522Z","time spent":"309.2735ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:03:05.014164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.916209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T10:03:05.014588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.923539ms","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-25T10:03:05.01465Z","caller":"traceutil/trace.go:171","msg":"trace[1127256597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:530; }","duration":"163.192544ms","start":"2026-02-25T10:03:04.851186Z","end":"2026-02-25T10:03:05.014378Z","steps":["trace[1127256597] 'range keys from in-memory index tree' (duration: 162.688304ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:05.014689Z","caller":"traceutil/trace.go:171","msg":"trace[1062245978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"163.074792ms","start":"2026-02-25T10:03:04.85159Z","end":"2026-02-25T10:03:05.014665Z","steps":["trace[1062245978] 'range keys from in-memory index tree' (duration: 162.471069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:05.973211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.698737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-khrc2\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-25T10:03:05.973284Z","caller":"traceutil/trace.go:171","msg":"trace[1411063720] range","detail":"{range_begin:/registry/pods/kube-system/cilium-khrc2; range_end:; response_count:1; response_revision:533; }","duration":"791.812718ms","start":"2026-02-25T10:03:05.181455Z","end":"2026-02-25T10:03:05.973267Z","steps":["trace[1411063720] 'range keys from in-memory index tree' (duration: 791.449021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:05.973332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:05.181434Z","time spent":"791.888841ms","remote":"127.0.0.1:41174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16676,"request content":"key:\"/registry/pods/kube-system/cilium-khrc2\" "} {"level":"warn","ts":"2026-02-25T10:03:05.973763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.219815ms","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-25T10:03:05.973861Z","caller":"traceutil/trace.go:171","msg":"trace[1976288243] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:533; }","duration":"354.358369ms","start":"2026-02-25T10:03:05.619473Z","end":"2026-02-25T10:03:05.973831Z","steps":["trace[1976288243] 'range keys from in-memory index tree' (duration: 353.986271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:05.973926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:05.619446Z","time spent":"354.449021ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T10:03:06.745089Z","caller":"traceutil/trace.go:171","msg":"trace[639445322] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"767.961631ms","start":"2026-02-25T10:03:05.977109Z","end":"2026-02-25T10:03:06.74507Z","steps":["trace[639445322] 'process raft request' (duration: 767.868239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:06.745193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:05.977087Z","time spent":"768.054073ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:03:06.758164Z","caller":"traceutil/trace.go:171","msg":"trace[775799055] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:550; }","duration":"143.357936ms","start":"2026-02-25T10:03:06.614774Z","end":"2026-02-25T10:03:06.758132Z","steps":["trace[775799055] 'read index received' (duration: 130.154834ms)","trace[775799055] 'applied index is now lower than readState.Index' (duration: 13.202092ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:03:06.758566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.791776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:03:06.75845Z","caller":"traceutil/trace.go:171","msg":"trace[248862301] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"772.934852ms","start":"2026-02-25T10:03:05.985504Z","end":"2026-02-25T10:03:06.758439Z","steps":["trace[248862301] 'process raft request' (duration: 772.473341ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:06.758613Z","caller":"traceutil/trace.go:171","msg":"trace[1664230662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"143.884248ms","start":"2026-02-25T10:03:06.614717Z","end":"2026-02-25T10:03:06.758602Z","steps":["trace[1664230662] 'agreement among raft nodes before linearized reading' (duration: 143.813507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:06.759028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:05.985477Z","time spent":"773.213337ms","remote":"127.0.0.1:41174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:03:08.525363Z","caller":"traceutil/trace.go:171","msg":"trace[164063039] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"199.864597ms","start":"2026-02-25T10:03:08.32546Z","end":"2026-02-25T10:03:08.525324Z","steps":["trace[164063039] 'process raft request' (duration: 199.620192ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:11.533236Z","caller":"traceutil/trace.go:171","msg":"trace[1036668522] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"166.990729ms","start":"2026-02-25T10:03:11.366215Z","end":"2026-02-25T10:03:11.533206Z","steps":["trace[1036668522] 'process raft request' (duration: 159.168486ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:25.429648Z","caller":"traceutil/trace.go:171","msg":"trace[1278864366] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"208.137621ms","start":"2026-02-25T10:03:25.221484Z","end":"2026-02-25T10:03:25.429621Z","steps":["trace[1278864366] 'read index received' (duration: 207.998898ms)","trace[1278864366] 'applied index is now lower than readState.Index' (duration: 137.593µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:25.429712Z","caller":"traceutil/trace.go:171","msg":"trace[1547267856] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"273.502289ms","start":"2026-02-25T10:03:25.156157Z","end":"2026-02-25T10:03:25.42966Z","steps":["trace[1547267856] 'process raft request' (duration: 273.301104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:25.429844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.350286ms","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-25T10:03:25.429886Z","caller":"traceutil/trace.go:171","msg":"trace[602769146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:725; }","duration":"208.456478ms","start":"2026-02-25T10:03:25.221418Z","end":"2026-02-25T10:03:25.429875Z","steps":["trace[602769146] 'agreement among raft nodes before linearized reading' (duration: 208.307615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:26.310066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.790345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:03:26.310312Z","caller":"traceutil/trace.go:171","msg":"trace[1033300274] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:747; }","duration":"694.650037ms","start":"2026-02-25T10:03:25.615646Z","end":"2026-02-25T10:03:26.310296Z","steps":["trace[1033300274] 'read index received' (duration: 215.584545ms)","trace[1033300274] 'applied index is now lower than readState.Index' (duration: 479.064682ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:26.3103Z","caller":"traceutil/trace.go:171","msg":"trace[1830365337] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"876.25802ms","start":"2026-02-25T10:03:25.434008Z","end":"2026-02-25T10:03:26.310266Z","steps":["trace[1830365337] 'process raft request' (duration: 86.030806ms)","trace[1830365337] 'compare' (duration: 789.637191ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:26.310388Z","caller":"traceutil/trace.go:171","msg":"trace[1077549779] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"807.118679ms","start":"2026-02-25T10:03:25.503253Z","end":"2026-02-25T10:03:26.310372Z","steps":["trace[1077549779] 'process raft request' (duration: 806.932415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:26.310397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.75487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:03:26.310425Z","caller":"traceutil/trace.go:171","msg":"trace[1322979561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:727; }","duration":"694.817521ms","start":"2026-02-25T10:03:25.615598Z","end":"2026-02-25T10:03:26.310416Z","steps":["trace[1322979561] 'agreement among raft nodes before linearized reading' (duration: 694.751379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:26.310448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:25.503228Z","time spent":"807.18837ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:03:26.310452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:25.433986Z","time spent":"876.380313ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:03:26.310457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:25.615578Z","time spent":"694.871862ms","remote":"127.0.0.1:40988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:03:26.761745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.749632ms","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-25T10:03:26.761826Z","caller":"traceutil/trace.go:171","msg":"trace[2094837614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:727; }","duration":"142.883755ms","start":"2026-02-25T10:03:26.618922Z","end":"2026-02-25T10:03:26.761806Z","steps":["trace[2094837614] 'range keys from in-memory index tree' (duration: 142.523167ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:26.867293Z","caller":"traceutil/trace.go:171","msg":"trace[217566419] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"102.087322ms","start":"2026-02-25T10:03:26.765189Z","end":"2026-02-25T10:03:26.867276Z","steps":["trace[217566419] 'process raft request' (duration: 101.796376ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:27.083156Z","caller":"traceutil/trace.go:171","msg":"trace[1543601347] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"212.18335ms","start":"2026-02-25T10:03:26.870947Z","end":"2026-02-25T10:03:27.083131Z","steps":["trace[1543601347] 'process raft request' (duration: 212.004866ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:27.096731Z","caller":"traceutil/trace.go:171","msg":"trace[1688734141] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"222.771045ms","start":"2026-02-25T10:03:26.873946Z","end":"2026-02-25T10:03:27.096717Z","steps":["trace[1688734141] 'process raft request' (duration: 222.696674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:27.934448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103530749,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:03:28.243497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.663102ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T10:03:28.243595Z","caller":"traceutil/trace.go:171","msg":"trace[442418561] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:752; }","duration":"809.674235ms","start":"2026-02-25T10:03:27.433907Z","end":"2026-02-25T10:03:28.243582Z","steps":["trace[442418561] 'read index received' (duration: 572.86143ms)","trace[442418561] 'applied index is now lower than readState.Index' (duration: 236.811585ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:03:28.24374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.834028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-25T10:03:28.243737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.574683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:03:28.243806Z","caller":"traceutil/trace.go:171","msg":"trace[1476393629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:730; }","duration":"628.690086ms","start":"2026-02-25T10:03:27.615099Z","end":"2026-02-25T10:03:28.243789Z","steps":["trace[1476393629] 'agreement among raft nodes before linearized reading' (duration: 628.568523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:28.243852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:27.615084Z","time spent":"628.760768ms","remote":"127.0.0.1:40988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T10:03:28.243766Z","caller":"traceutil/trace.go:171","msg":"trace[1452135837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:730; }","duration":"809.89416ms","start":"2026-02-25T10:03:27.433864Z","end":"2026-02-25T10:03:28.243759Z","steps":["trace[1452135837] 'agreement among raft nodes before linearized reading' (duration: 809.780967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:28.243929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:27.433848Z","time spent":"810.067463ms","remote":"127.0.0.1:41256","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-25T10:03:28.495788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.713396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:03:28.495887Z","caller":"traceutil/trace.go:171","msg":"trace[42294286] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"178.503014ms","start":"2026-02-25T10:03:28.317364Z","end":"2026-02-25T10:03:28.495867Z","steps":["trace[42294286] 'process raft request' (duration: 75.625455ms)","trace[42294286] 'compare' (duration: 102.585293ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:49.432869Z","caller":"traceutil/trace.go:171","msg":"trace[1937154468] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"175.051428ms","start":"2026-02-25T10:03:49.257791Z","end":"2026-02-25T10:03:49.432843Z","steps":["trace[1937154468] 'process raft request' (duration: 172.572722ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:50.494229Z","caller":"traceutil/trace.go:171","msg":"trace[726171515] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"130.632866ms","start":"2026-02-25T10:03:50.363562Z","end":"2026-02-25T10:03:50.494194Z","steps":["trace[726171515] 'process raft request' (duration: 130.445932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:50.896401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.398941ms","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-25T10:03:50.896569Z","caller":"traceutil/trace.go:171","msg":"trace[1491560454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"290.627246ms","start":"2026-02-25T10:03:50.60592Z","end":"2026-02-25T10:03:50.896548Z","steps":["trace[1491560454] 'range keys from in-memory index tree' (duration: 290.209376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:50.896423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.276166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:03:50.896737Z","caller":"traceutil/trace.go:171","msg":"trace[1938681621] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:798; }","duration":"259.584463ms","start":"2026-02-25T10:03:50.637113Z","end":"2026-02-25T10:03:50.896698Z","steps":["trace[1938681621] 'count revisions from in-memory index tree' (duration: 259.131024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:51.615327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103530952,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:03:51.833746Z","caller":"traceutil/trace.go:171","msg":"trace[1315257020] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:826; }","duration":"718.468004ms","start":"2026-02-25T10:03:51.115253Z","end":"2026-02-25T10:03:51.833721Z","steps":["trace[1315257020] 'read index received' (duration: 718.27794ms)","trace[1315257020] 'applied index is now lower than readState.Index' (duration: 189.154µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:51.833797Z","caller":"traceutil/trace.go:171","msg":"trace[1856329127] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"932.87453ms","start":"2026-02-25T10:03:50.90089Z","end":"2026-02-25T10:03:51.833765Z","steps":["trace[1856329127] 'process raft request' (duration: 932.704907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:51.834465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:50.900872Z","time spent":"933.014603ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:03:51.834615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.218002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T10:03:51.834616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.270224ms","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-25T10:03:51.834808Z","caller":"traceutil/trace.go:171","msg":"trace[1944291441] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"719.57087ms","start":"2026-02-25T10:03:51.115186Z","end":"2026-02-25T10:03:51.834757Z","steps":["trace[1944291441] 'agreement among raft nodes before linearized reading' (duration: 718.706271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:51.834905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.498298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:03:51.834953Z","caller":"traceutil/trace.go:171","msg":"trace[558710535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"219.57404ms","start":"2026-02-25T10:03:51.615355Z","end":"2026-02-25T10:03:51.834929Z","steps":["trace[558710535] 'agreement among raft nodes before linearized reading' (duration: 219.402136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:51.83494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.243417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:03:51.835043Z","caller":"traceutil/trace.go:171","msg":"trace[1007618602] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:799; }","duration":"333.392731ms","start":"2026-02-25T10:03:51.501615Z","end":"2026-02-25T10:03:51.835008Z","steps":["trace[1007618602] 'agreement among raft nodes before linearized reading' (duration: 333.016782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:51.835086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.094935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T10:03:51.835103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:51.501595Z","time spent":"333.495074ms","remote":"127.0.0.1:41362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":66,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-02-25T10:03:51.835152Z","caller":"traceutil/trace.go:171","msg":"trace[303159672] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:799; }","duration":"398.199678ms","start":"2026-02-25T10:03:51.436938Z","end":"2026-02-25T10:03:51.835137Z","steps":["trace[303159672] 'agreement among raft nodes before linearized reading' (duration: 397.702707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:51.835215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:51.436919Z","time spent":"398.264719ms","remote":"127.0.0.1:41256","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":"info","ts":"2026-02-25T10:03:51.834706Z","caller":"traceutil/trace.go:171","msg":"trace[1308140560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:799; }","duration":"581.350805ms","start":"2026-02-25T10:03:51.253334Z","end":"2026-02-25T10:03:51.834685Z","steps":["trace[1308140560] 'agreement among raft nodes before linearized reading' (duration: 581.1562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:51.834904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:51.115173Z","time spent":"719.715633ms","remote":"127.0.0.1:41256","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-25T10:03:51.83552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:51.253309Z","time spent":"582.093121ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:03:52.309523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.529261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:03:52.30963Z","caller":"traceutil/trace.go:171","msg":"trace[2030104948] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"468.435095ms","start":"2026-02-25T10:03:51.841176Z","end":"2026-02-25T10:03:52.309611Z","steps":["trace[2030104948] 'process raft request' (duration: 338.73614ms)","trace[2030104948] 'compare' (duration: 129.259695ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:03:52.309687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:51.841163Z","time spent":"468.497326ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:03:52.702375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.711702ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:03:52.702579Z","caller":"traceutil/trace.go:171","msg":"trace[1619022536] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"861.309822ms","start":"2026-02-25T10:03:51.841233Z","end":"2026-02-25T10:03:52.702542Z","steps":["trace[1619022536] 'process raft request' (duration: 590.325004ms)","trace[1619022536] 'compare' (duration: 270.59591ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:03:52.702665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:51.841212Z","time spent":"861.409525ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:03:52.702928Z","caller":"traceutil/trace.go:171","msg":"trace[1296412596] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"861.116998ms","start":"2026-02-25T10:03:51.84179Z","end":"2026-02-25T10:03:52.702907Z","steps":["trace[1296412596] 'process raft request' (duration: 860.691369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:52.70326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:51.84178Z","time spent":"861.408775ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:03:52.702919Z","caller":"traceutil/trace.go:171","msg":"trace[381284063] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:827; }","duration":"604.224245ms","start":"2026-02-25T10:03:52.098652Z","end":"2026-02-25T10:03:52.702876Z","steps":["trace[381284063] 'read index received' (duration: 81.289474ms)","trace[381284063] 'applied index is now lower than readState.Index' (duration: 522.932511ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:03:52.703073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.370919ms","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-25T10:03:52.703576Z","caller":"traceutil/trace.go:171","msg":"trace[2085579367] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:802; }","duration":"604.941871ms","start":"2026-02-25T10:03:52.098617Z","end":"2026-02-25T10:03:52.703558Z","steps":["trace[2085579367] 'agreement among raft nodes before linearized reading' (duration: 604.357718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:52.703687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:52.098601Z","time spent":"605.059634ms","remote":"127.0.0.1:41486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:03:52.704006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.892617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T10:03:52.704062Z","caller":"traceutil/trace.go:171","msg":"trace[713605059] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:802; }","duration":"581.974069ms","start":"2026-02-25T10:03:52.122073Z","end":"2026-02-25T10:03:52.704047Z","steps":["trace[713605059] 'agreement among raft nodes before linearized reading' (duration: 581.805466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:52.704099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:52.122059Z","time spent":"582.03322ms","remote":"127.0.0.1:41036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.36\" "} {"level":"warn","ts":"2026-02-25T10:03:52.704259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.337866ms","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-25T10:03:52.704316Z","caller":"traceutil/trace.go:171","msg":"trace[1369270487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:802; }","duration":"206.426957ms","start":"2026-02-25T10:03:52.497874Z","end":"2026-02-25T10:03:52.704301Z","steps":["trace[1369270487] 'agreement among raft nodes before linearized reading' (duration: 206.255423ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:52.848936Z","caller":"traceutil/trace.go:171","msg":"trace[1377117203] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"140.744991ms","start":"2026-02-25T10:03:52.708163Z","end":"2026-02-25T10:03:52.848908Z","steps":["trace[1377117203] 'process raft request' (duration: 140.196329ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:52.861012Z","caller":"traceutil/trace.go:171","msg":"trace[917621224] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"142.731485ms","start":"2026-02-25T10:03:52.718257Z","end":"2026-02-25T10:03:52.860988Z","steps":["trace[917621224] 'process raft request' (duration: 142.581692ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:52.861249Z","caller":"traceutil/trace.go:171","msg":"trace[15643674] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"139.020083ms","start":"2026-02-25T10:03:52.72221Z","end":"2026-02-25T10:03:52.86123Z","steps":["trace[15643674] 'process raft request' (duration: 138.725837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:55.353979Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103530979,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:03:55.449239Z","caller":"traceutil/trace.go:171","msg":"trace[517225996] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"596.30454ms","start":"2026-02-25T10:03:54.852914Z","end":"2026-02-25T10:03:55.449218Z","steps":["trace[517225996] 'read index received' (duration: 596.101335ms)","trace[517225996] 'applied index is now lower than readState.Index' (duration: 202.305µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:55.449383Z","caller":"traceutil/trace.go:171","msg":"trace[456044545] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"737.863219ms","start":"2026-02-25T10:03:54.711487Z","end":"2026-02-25T10:03:55.449351Z","steps":["trace[456044545] 'process raft request' (duration: 737.611873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:55.449548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:54.711476Z","time spent":"737.975572ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:03:55.449453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.528184ms","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-25T10:03:55.44987Z","caller":"traceutil/trace.go:171","msg":"trace[1632218142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:809; }","duration":"596.994005ms","start":"2026-02-25T10:03:54.852862Z","end":"2026-02-25T10:03:55.449856Z","steps":["trace[1632218142] 'agreement among raft nodes before linearized reading' (duration: 596.439443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:55.449914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:54.852843Z","time spent":"597.058526ms","remote":"127.0.0.1:41256","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":"info","ts":"2026-02-25T10:03:55.7825Z","caller":"traceutil/trace.go:171","msg":"trace[1388931103] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"328.452322ms","start":"2026-02-25T10:03:55.453993Z","end":"2026-02-25T10:03:55.782445Z","steps":["trace[1388931103] 'process raft request' (duration: 228.575632ms)","trace[1388931103] 'compare' (duration: 99.709125ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:03:55.782702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:55.453972Z","time spent":"328.610585ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:03:56.029145Z","caller":"traceutil/trace.go:171","msg":"trace[50728903] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"155.368478ms","start":"2026-02-25T10:03:55.873725Z","end":"2026-02-25T10:03:56.029094Z","steps":["trace[50728903] 'process raft request' (duration: 93.819995ms)","trace[50728903] 'compare' (duration: 61.195375ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:04:07.675124Z","caller":"traceutil/trace.go:171","msg":"trace[458560106] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"172.229034ms","start":"2026-02-25T10:04:07.502873Z","end":"2026-02-25T10:04:07.675102Z","steps":["trace[458560106] 'process raft request' (duration: 171.781654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:08.621659Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531166,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:09.122608Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531166,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:09.351194Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.385714478s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T10:04:09.379433Z","caller":"traceutil/trace.go:171","msg":"trace[1141324640] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"1.258828705s","start":"2026-02-25T10:04:08.120577Z","end":"2026-02-25T10:04:09.379406Z","steps":["trace[1141324640] 'read index received' (duration: 1.230742278s)","trace[1141324640] 'applied index is now lower than readState.Index' (duration: 28.085087ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:09.379666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259078071s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-25T10:04:09.379758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.168465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:04:09.379745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.413489ms","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-25T10:04:09.379777Z","caller":"traceutil/trace.go:171","msg":"trace[1552567371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"1.259215654s","start":"2026-02-25T10:04:08.120533Z","end":"2026-02-25T10:04:09.379749Z","steps":["trace[1552567371] 'agreement among raft nodes before linearized reading' (duration: 1.258997919s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:09.37982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:08.120517Z","time spent":"1.259294266s","remote":"127.0.0.1:41256","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-25T10:04:09.379806Z","caller":"traceutil/trace.go:171","msg":"trace[1069532420] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:895; }","duration":"582.534991ms","start":"2026-02-25T10:04:08.797254Z","end":"2026-02-25T10:04:09.379789Z","steps":["trace[1069532420] 'agreement among raft nodes before linearized reading' (duration: 582.329837ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:09.379796Z","caller":"traceutil/trace.go:171","msg":"trace[1777130760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"764.224276ms","start":"2026-02-25T10:04:08.615555Z","end":"2026-02-25T10:04:09.379779Z","steps":["trace[1777130760] 'agreement among raft nodes before linearized reading' (duration: 764.186165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:09.379863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:08.615539Z","time spent":"764.318768ms","remote":"127.0.0.1:40988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:04:09.37987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:08.79723Z","time spent":"582.628834ms","remote":"127.0.0.1:41256","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":"warn","ts":"2026-02-25T10:04:09.379707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.006852ms","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-25T10:04:09.380019Z","caller":"traceutil/trace.go:171","msg":"trace[601327992] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:895; }","duration":"984.37399ms","start":"2026-02-25T10:04:08.395635Z","end":"2026-02-25T10:04:09.380009Z","steps":["trace[601327992] 'agreement among raft nodes before linearized reading' (duration: 983.940081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:09.38005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:08.395615Z","time spent":"984.426381ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T10:04:09.507483Z","caller":"traceutil/trace.go:171","msg":"trace[518113661] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"122.914544ms","start":"2026-02-25T10:04:09.38455Z","end":"2026-02-25T10:04:09.507464Z","steps":["trace[518113661] 'process raft request' (duration: 57.450703ms)","trace[518113661] 'compare' (duration: 65.308277ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:04:09.507633Z","caller":"traceutil/trace.go:171","msg":"trace[1340893519] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"122.73793ms","start":"2026-02-25T10:04:09.38487Z","end":"2026-02-25T10:04:09.507608Z","steps":["trace[1340893519] 'process raft request' (duration: 122.562966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:11.574358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.359583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:04:11.57442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.84773ms","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-25T10:04:11.574452Z","caller":"traceutil/trace.go:171","msg":"trace[1229146718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"959.494806ms","start":"2026-02-25T10:04:10.614934Z","end":"2026-02-25T10:04:11.574428Z","steps":["trace[1229146718] 'range keys from in-memory index tree' (duration: 959.28156ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:11.574484Z","caller":"traceutil/trace.go:171","msg":"trace[1993769993] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:900; }","duration":"147.958743ms","start":"2026-02-25T10:04:11.426507Z","end":"2026-02-25T10:04:11.574466Z","steps":["trace[1993769993] 'range keys from in-memory index tree' (duration: 147.662916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:11.574508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:10.614913Z","time spent":"959.581927ms","remote":"127.0.0.1:40988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:04:11.574404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.913564ms","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-25T10:04:11.574674Z","caller":"traceutil/trace.go:171","msg":"trace[1564315819] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:900; }","duration":"606.19441ms","start":"2026-02-25T10:04:10.968468Z","end":"2026-02-25T10:04:11.574663Z","steps":["trace[1564315819] 'range keys from in-memory index tree' (duration: 605.899863ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:12.002242Z","caller":"traceutil/trace.go:171","msg":"trace[731879218] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"423.343549ms","start":"2026-02-25T10:04:11.578874Z","end":"2026-02-25T10:04:12.002218Z","steps":["trace[731879218] 'process raft request' (duration: 423.207446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:12.002391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:11.578852Z","time spent":"423.458912ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:04:12.02653Z","caller":"traceutil/trace.go:171","msg":"trace[13013908] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"447.389905ms","start":"2026-02-25T10:04:11.57913Z","end":"2026-02-25T10:04:12.026519Z","steps":["trace[13013908] 'process raft request' (duration: 447.295303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:12.026602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:11.579118Z","time spent":"447.446276ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:04:12.026766Z","caller":"traceutil/trace.go:171","msg":"trace[1923322177] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:934; }","duration":"326.864345ms","start":"2026-02-25T10:04:11.699894Z","end":"2026-02-25T10:04:12.026758Z","steps":["trace[1923322177] 'read index received' (duration: 302.198094ms)","trace[1923322177] 'applied index is now lower than readState.Index' (duration: 24.66559ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:04:12.026875Z","caller":"traceutil/trace.go:171","msg":"trace[2075657731] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"447.368864ms","start":"2026-02-25T10:04:11.579499Z","end":"2026-02-25T10:04:12.026868Z","steps":["trace[2075657731] 'process raft request' (duration: 446.984535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:12.026915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:11.579488Z","time spent":"447.405174ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:04:12.027048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.34043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:04:12.027121Z","caller":"traceutil/trace.go:171","msg":"trace[1410562815] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:903; }","duration":"316.454253ms","start":"2026-02-25T10:04:11.710652Z","end":"2026-02-25T10:04:12.027106Z","steps":["trace[1410562815] 'agreement among raft nodes before linearized reading' (duration: 316.32152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:12.027115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.228612ms","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-25T10:04:12.027153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:11.710638Z","time spent":"316.508284ms","remote":"127.0.0.1:41274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-25T10:04:12.027175Z","caller":"traceutil/trace.go:171","msg":"trace[531927057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:903; }","duration":"327.319114ms","start":"2026-02-25T10:04:11.699843Z","end":"2026-02-25T10:04:12.027162Z","steps":["trace[531927057] 'agreement among raft nodes before linearized reading' (duration: 327.231722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:12.027213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:11.69982Z","time spent":"327.385216ms","remote":"127.0.0.1:41256","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-25T10:04:12.027299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.617521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:04:12.027327Z","caller":"traceutil/trace.go:171","msg":"trace[2055394075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:903; }","duration":"193.687363ms","start":"2026-02-25T10:04:11.833632Z","end":"2026-02-25T10:04:12.02732Z","steps":["trace[2055394075] 'agreement among raft nodes before linearized reading' (duration: 193.649552ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:12.141211Z","caller":"traceutil/trace.go:171","msg":"trace[546889732] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"109.878162ms","start":"2026-02-25T10:04:12.03131Z","end":"2026-02-25T10:04:12.141188Z","steps":["trace[546889732] 'process raft request' (duration: 109.80507ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:12.141213Z","caller":"traceutil/trace.go:171","msg":"trace[1838274573] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"110.287472ms","start":"2026-02-25T10:04:12.030893Z","end":"2026-02-25T10:04:12.141181Z","steps":["trace[1838274573] 'process raft request' (duration: 93.129989ms)","trace[1838274573] 'compare' (duration: 16.973379ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:16.544174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:17.044578Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:17.545809Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:18.046628Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:18.547259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:18.614234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000175411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:04:18.614314Z","caller":"traceutil/trace.go:171","msg":"trace[835613368] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000303814s","start":"2026-02-25T10:04:16.613989Z","end":"2026-02-25T10:04:18.614293Z","steps":["trace[835613368] 'agreement among raft nodes before linearized reading' (duration: 2.000205592s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:18.614362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:16.613969Z","time spent":"2.000383226s","remote":"127.0.0.1:40988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 10:04:18 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:19.048132Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:19.548617Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:20.049539Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:20.55027Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:20.614904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000354465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:04:20.614985Z","caller":"traceutil/trace.go:171","msg":"trace[789527515] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000477358s","start":"2026-02-25T10:04:18.614488Z","end":"2026-02-25T10:04:20.614965Z","steps":["trace[789527515] 'agreement among raft nodes before linearized reading' (duration: 2.000374466s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:20.615046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:18.614473Z","time spent":"2.00056566s","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 10:04:20 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:21.020495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:16.02125Z","time spent":"4.999238287s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-25T10:04:21.043598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999457862s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:04:21.043678Z","caller":"traceutil/trace.go:171","msg":"trace[2082660101] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999580465s","start":"2026-02-25T10:04:16.04408Z","end":"2026-02-25T10:04:21.04366Z","steps":["trace[2082660101] 'agreement among raft nodes before linearized reading' (duration: 4.999477533s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:21.043724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:16.044067Z","time spent":"4.999648486s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/02/25 10:04:21 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:21.050713Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:21.153117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.99979936s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:04:21.153202Z","caller":"traceutil/trace.go:171","msg":"trace[819593830] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999912023s","start":"2026-02-25T10:04:16.153274Z","end":"2026-02-25T10:04:21.153186Z","steps":["trace[819593830] 'agreement among raft nodes before linearized reading' (duration: 4.999818041s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:21.153248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:16.153264Z","time spent":"4.999975544s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/02/25 10:04:21 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:21.551018Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:22.051334Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:22.552321Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:22.622634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000378616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T10:04:22.622695Z","caller":"traceutil/trace.go:171","msg":"trace[1140112470] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000476247s","start":"2026-02-25T10:04:20.622206Z","end":"2026-02-25T10:04:22.622682Z","steps":["trace[1140112470] 'agreement among raft nodes before linearized reading' (duration: 2.000402386s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:22.622724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:20.622195Z","time spent":"2.000523549s","remote":"127.0.0.1:40988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 10:04:22 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:23.044825Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-02-25T10:04:23.044988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.000862041s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-25T10:04:23.045052Z","caller":"traceutil/trace.go:171","msg":"trace[319173359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"7.000961393s","start":"2026-02-25T10:04:16.044064Z","end":"2026-02-25T10:04:23.045025Z","steps":["trace[319173359] 'agreement among raft nodes before linearized reading' (duration: 7.000895992s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:23.045101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:16.044044Z","time spent":"7.001045804s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T10:04:23.545714Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531226,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:23.832818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000229703s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T10:04:23.832903Z","caller":"traceutil/trace.go:171","msg":"trace[1216227059] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000403607s","start":"2026-02-25T10:04:21.832479Z","end":"2026-02-25T10:04:23.832882Z","steps":["trace[1216227059] 'agreement among raft nodes before linearized reading' (duration: 2.000250753s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:23.832955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:21.832463Z","time spent":"2.000478828s","remote":"127.0.0.1:40988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 10:04:23 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:23.921478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:16.921233Z","time spent":"7.000237147s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-25T10:04:24.046771Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531226,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:24.478614Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"7f0b9c9440007a18","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-25T10:04:24.547115Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531226,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:24.622747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999904716s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T10:04:24.622829Z","caller":"traceutil/trace.go:171","msg":"trace[414853182] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000047379s","start":"2026-02-25T10:04:22.622766Z","end":"2026-02-25T10:04:24.622813Z","steps":["trace[414853182] 'agreement among raft nodes before linearized reading' (duration: 1.999941866s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:24.622868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:22.622736Z","time spent":"2.000125s","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:04:25.047944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531226,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:25.548095Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531226,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:26.017845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.995264081s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:04:26.017927Z","caller":"traceutil/trace.go:171","msg":"trace[256474730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.995391495s","start":"2026-02-25T10:04:23.022515Z","end":"2026-02-25T10:04:26.017907Z","steps":["trace[256474730] 'agreement among raft nodes before linearized reading' (duration: 2.995287072s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:26.017975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:23.0225Z","time spent":"2.995465456s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/25 10:04:26 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:26.04251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997121401s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:04:26.042572Z","caller":"traceutil/trace.go:171","msg":"trace[672002492] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.997205183s","start":"2026-02-25T10:04:23.045343Z","end":"2026-02-25T10:04:26.042549Z","steps":["trace[672002492] 'agreement among raft nodes before linearized reading' (duration: 2.997152622s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:26.042604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:23.045331Z","time spent":"2.997265484s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/02/25 10:04:26 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:26.0466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.376364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:04:26.046632Z","caller":"traceutil/trace.go:171","msg":"trace[540762504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"998.410034ms","start":"2026-02-25T10:04:25.048215Z","end":"2026-02-25T10:04:26.046625Z","steps":["trace[540762504] 'agreement among raft nodes before linearized reading' (duration: 998.375824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:26.046652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:25.04816Z","time spent":"998.487666ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} 2026/02/25 10:04:26 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:26.048532Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531226,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:26.152502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999775583s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:04:26.152597Z","caller":"traceutil/trace.go:171","msg":"trace[2025530176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"9.999919516s","start":"2026-02-25T10:04:16.152661Z","end":"2026-02-25T10:04:26.15258Z","steps":["trace[2025530176] 'agreement among raft nodes before linearized reading' (duration: 9.999808534s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:26.152636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:16.152644Z","time spent":"9.999983817s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/02/25 10:04:26 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:26.153628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998720978s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:04:26.153688Z","caller":"traceutil/trace.go:171","msg":"trace[609260609] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.998832519s","start":"2026-02-25T10:04:23.154841Z","end":"2026-02-25T10:04:26.153674Z","steps":["trace[609260609] 'agreement among raft nodes before linearized reading' (duration: 2.998755958s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:26.153719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:23.1548Z","time spent":"2.998912511s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/02/25 10:04:26 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:26.549271Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531226,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:26.630727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000697452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T10:04:26.630815Z","caller":"traceutil/trace.go:171","msg":"trace[1501555670] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000834036s","start":"2026-02-25T10:04:24.629964Z","end":"2026-02-25T10:04:26.630798Z","steps":["trace[1501555670] 'agreement among raft nodes before linearized reading' (duration: 2.000720173s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:26.630863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:24.629945Z","time spent":"2.000909408s","remote":"127.0.0.1:40988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:04:27.04978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531226,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:27.550804Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531226,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:27.618501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:20.617848Z","time spent":"7.000648615s","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-25T10:04:28.051024Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531226,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:28.552194Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531226,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:28.63162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000231963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T10:04:28.631703Z","caller":"traceutil/trace.go:171","msg":"trace[673606031] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000387266s","start":"2026-02-25T10:04:26.631297Z","end":"2026-02-25T10:04:28.631684Z","steps":["trace[673606031] 'agreement among raft nodes before linearized reading' (duration: 2.000285274s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:28.631758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:26.631281Z","time spent":"2.000461048s","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:04:29.052594Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531226,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:29.55376Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531226,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:30.045657Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-02-25T10:04:30.04584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.796596018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-25T10:04:30.04584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.678228425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-25T10:04:30.045898Z","caller":"traceutil/trace.go:171","msg":"trace[1506831468] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; }","duration":"9.678348008s","start":"2026-02-25T10:04:20.367536Z","end":"2026-02-25T10:04:30.045884Z","steps":["trace[1506831468] 'agreement among raft nodes before linearized reading' (duration: 9.678263016s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:30.045888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.66869793s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-25T10:04:30.045948Z","caller":"traceutil/trace.go:171","msg":"trace[730027624] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; }","duration":"11.668813064s","start":"2026-02-25T10:04:18.377121Z","end":"2026-02-25T10:04:30.045934Z","steps":["trace[730027624] 'agreement among raft nodes before linearized reading' (duration: 11.668714421s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:30.045944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:20.367517Z","time spent":"9.67841499s","remote":"127.0.0.1:41480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":0,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:30.045954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.477296398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-25T10:04:30.045978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:18.377107Z","time spent":"11.668864255s","remote":"127.0.0.1:41320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":0,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:30.045948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.877843312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-25T10:04:30.045997Z","caller":"traceutil/trace.go:171","msg":"trace[387943919] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; }","duration":"12.477403301s","start":"2026-02-25T10:04:17.568584Z","end":"2026-02-25T10:04:30.045987Z","steps":["trace[387943919] 'agreement among raft nodes before linearized reading' (duration: 12.4773396s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:30.046029Z","caller":"traceutil/trace.go:171","msg":"trace[1584880901] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; }","duration":"8.877951156s","start":"2026-02-25T10:04:21.168059Z","end":"2026-02-25T10:04:30.04601Z","steps":["trace[1584880901] 'agreement among raft nodes before linearized reading' (duration: 8.877838152s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:30.046041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:17.568556Z","time spent":"12.477473582s","remote":"127.0.0.1:41438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":0,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:30.046074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:21.168019Z","time spent":"8.878043868s","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":0,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:30.046246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.922417669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-25T10:04:30.045888Z","caller":"traceutil/trace.go:171","msg":"trace[842177135] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; }","duration":"9.79669447s","start":"2026-02-25T10:04:20.249175Z","end":"2026-02-25T10:04:30.04587Z","steps":["trace[842177135] 'agreement among raft nodes before linearized reading' (duration: 9.796616688s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:30.046319Z","caller":"traceutil/trace.go:171","msg":"trace[936137012] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; }","duration":"7.922567191s","start":"2026-02-25T10:04:22.123743Z","end":"2026-02-25T10:04:30.04631Z","steps":["trace[936137012] 'agreement among raft nodes before linearized reading' (duration: 7.922445059s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:30.046347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:22.123728Z","time spent":"7.922611863s","remote":"127.0.0.1:41036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":0,"request content":"key:\"/registry/masterleases/162.253.55.36\" "} {"level":"warn","ts":"2026-02-25T10:04:30.046349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:20.249165Z","time spent":"9.797170271s","remote":"127.0.0.1:41242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":0,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:30.262683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:23.26204Z","time spent":"7.000635655s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-25T10:04:30.545818Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:30.642401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000234943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T10:04:30.642488Z","caller":"traceutil/trace.go:171","msg":"trace[878416046] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000364006s","start":"2026-02-25T10:04:28.6421Z","end":"2026-02-25T10:04:30.642464Z","steps":["trace[878416046] 'agreement among raft nodes before linearized reading' (duration: 2.000264254s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:30.642548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:28.642084Z","time spent":"2.000451898s","remote":"127.0.0.1:40988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:04:30.926492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:23.925882Z","time spent":"7.000603415s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-25T10:04:31.017977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:26.019632Z","time spent":"4.998331006s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-25T10:04:31.043862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:26.044319Z","time spent":"4.999534654s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-25T10:04:31.046284Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:31.484427Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"7f0b9c9440007a18","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-25T10:04:31.547042Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:32.047619Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:32.548521Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:32.64343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000183102s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:04:32.643498Z","caller":"traceutil/trace.go:171","msg":"trace[545730770] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000293815s","start":"2026-02-25T10:04:30.643187Z","end":"2026-02-25T10:04:32.64348Z","steps":["trace[545730770] 'agreement among raft nodes before linearized reading' (duration: 2.000211083s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:32.64354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:30.643169Z","time spent":"2.000363156s","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 10:04:32 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:33.047743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:26.047421Z","time spent":"7.000313123s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-25T10:04:33.048867Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:33.052856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:26.155316Z","time spent":"6.897535469s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/25 10:04:33 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:33.549702Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:33.832481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00009585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:04:33.832556Z","caller":"traceutil/trace.go:171","msg":"trace[1421203949] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000227272s","start":"2026-02-25T10:04:31.832311Z","end":"2026-02-25T10:04:33.832538Z","steps":["trace[1421203949] 'agreement among raft nodes before linearized reading' (duration: 2.00013722s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:33.832598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:31.832291Z","time spent":"2.000298744s","remote":"127.0.0.1:40988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 10:04:33 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:34.050799Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:34.488379Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"7f0b9c9440007ab8","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-25T10:04:34.550976Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:34.623549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:27.623418Z","time spent":"7.000123568s","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-25T10:04:34.652392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000200822s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:04:34.652477Z","caller":"traceutil/trace.go:171","msg":"trace[305583409] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000316984s","start":"2026-02-25T10:04:32.652137Z","end":"2026-02-25T10:04:34.652454Z","steps":["trace[305583409] 'agreement among raft nodes before linearized reading' (duration: 2.000192972s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:34.652526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:32.652085Z","time spent":"2.000430847s","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 10:04:34 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:35.05132Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:35.552441Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:36.05319Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:36.553732Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:36.661093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000242085s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T10:04:36.661181Z","caller":"traceutil/trace.go:171","msg":"trace[2040096758] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000403818s","start":"2026-02-25T10:04:34.660757Z","end":"2026-02-25T10:04:36.661161Z","steps":["trace[2040096758] 'agreement among raft nodes before linearized reading' (duration: 2.000287736s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:36.661238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:34.660744Z","time spent":"2.00047772s","remote":"127.0.0.1:40988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 10:04:36 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:37.04651Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-02-25T10:04:37.046663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.102609012s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-25T10:04:37.046698Z","caller":"traceutil/trace.go:171","msg":"trace[2014734439] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; }","duration":"7.102709274s","start":"2026-02-25T10:04:29.943974Z","end":"2026-02-25T10:04:37.046683Z","steps":["trace[2014734439] 'agreement among raft nodes before linearized reading' (duration: 7.102645333s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:37.046678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.752189349s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-25T10:04:37.04673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:29.943953Z","time spent":"7.102767526s","remote":"127.0.0.1:41288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":0,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:37.046761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.893663126s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-25T10:04:37.046756Z","caller":"traceutil/trace.go:171","msg":"trace[22211693] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; }","duration":"8.752326701s","start":"2026-02-25T10:04:28.294412Z","end":"2026-02-25T10:04:37.046739Z","steps":["trace[22211693] 'agreement among raft nodes before linearized reading' (duration: 8.752217629s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:37.046786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.378172848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-25T10:04:37.046799Z","caller":"traceutil/trace.go:171","msg":"trace[682974372] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; }","duration":"10.378211548s","start":"2026-02-25T10:04:26.668584Z","end":"2026-02-25T10:04:37.046796Z","steps":["trace[682974372] 'agreement among raft nodes before linearized reading' (duration: 10.378197428s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:37.046811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:26.668572Z","time spent":"10.37823509s","remote":"127.0.0.1:41174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":0,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:37.046809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:28.294392Z","time spent":"8.752403253s","remote":"127.0.0.1:41096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":0,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:37.046828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.840942235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-25T10:04:37.046847Z","caller":"traceutil/trace.go:171","msg":"trace[1962655446] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; }","duration":"11.840996046s","start":"2026-02-25T10:04:25.205847Z","end":"2026-02-25T10:04:37.046843Z","steps":["trace[1962655446] 'agreement among raft nodes before linearized reading' (duration: 11.840977906s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:37.046859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:25.205829Z","time spent":"11.841026237s","remote":"127.0.0.1:41194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":0,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:37.046902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.410759539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-25T10:04:37.046942Z","caller":"traceutil/trace.go:171","msg":"trace[1698870571] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; }","duration":"8.410832409s","start":"2026-02-25T10:04:28.6361Z","end":"2026-02-25T10:04:37.046933Z","steps":["trace[1698870571] 'agreement among raft nodes before linearized reading' (duration: 8.410790839s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:37.046977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:28.636086Z","time spent":"8.410882331s","remote":"127.0.0.1:41174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":0,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-02-25T10:04:37.046773Z","caller":"traceutil/trace.go:171","msg":"trace[2102920254] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; }","duration":"8.893712927s","start":"2026-02-25T10:04:28.153055Z","end":"2026-02-25T10:04:37.046768Z","steps":["trace[2102920254] 'agreement among raft nodes before linearized reading' (duration: 8.893701487s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:37.047357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:28.153033Z","time spent":"8.894308959s","remote":"127.0.0.1:41398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:37.047136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.976371343s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-25T10:04:37.047429Z","caller":"traceutil/trace.go:171","msg":"trace[1915801319] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; }","duration":"7.97669716s","start":"2026-02-25T10:04:29.070713Z","end":"2026-02-25T10:04:37.04741Z","steps":["trace[1915801319] 'agreement among raft nodes before linearized reading' (duration: 7.976364543s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:37.047463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:29.070648Z","time spent":"7.976805583s","remote":"127.0.0.1:41334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":0,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:37.047537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.570136087s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-25T10:04:37.047636Z","caller":"traceutil/trace.go:171","msg":"trace[409831670] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; }","duration":"7.570939944s","start":"2026-02-25T10:04:29.476677Z","end":"2026-02-25T10:04:37.047617Z","steps":["trace[409831670] 'agreement among raft nodes before linearized reading' (duration: 7.570175678s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:37.047694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:29.476659Z","time spent":"7.571018476s","remote":"127.0.0.1:41190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":0,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:37.266821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:30.266466Z","time spent":"7.000347981s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-25T10:04:37.547255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531241,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:37.788906Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"21.767537731s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T10:04:37.931708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:30.93092Z","time spent":"7.000781514s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-25T10:04:38.492869Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"7f0b9c9440007a18","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-25T10:04:38.662624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001282737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:04:38.662785Z","caller":"traceutil/trace.go:171","msg":"trace[669466977] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001506092s","start":"2026-02-25T10:04:36.661241Z","end":"2026-02-25T10:04:38.662747Z","steps":["trace[669466977] 'agreement among raft nodes before linearized reading' (duration: 2.001314708s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:38.662856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:36.661209Z","time spent":"2.001634176s","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 10:04:38 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:40.669721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000133192s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T10:04:40.669948Z","caller":"traceutil/trace.go:171","msg":"trace[1363150631] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000443918s","start":"2026-02-25T10:04:38.669486Z","end":"2026-02-25T10:04:40.66993Z","steps":["trace[1363150631] 'agreement among raft nodes before linearized reading' (duration: 2.000156082s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:40.669998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:38.669468Z","time spent":"2.00051715s","remote":"127.0.0.1:40988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 10:04:40 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:41.495369Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"7f0b9c9440007ab8","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-25T10:04:42.670208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000035802s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:04:42.670348Z","caller":"traceutil/trace.go:171","msg":"trace[2091845138] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000247497s","start":"2026-02-25T10:04:40.670075Z","end":"2026-02-25T10:04:42.670323Z","steps":["trace[2091845138] 'agreement among raft nodes before linearized reading' (duration: 2.000065793s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:42.670413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:40.670055Z","time spent":"2.000344548s","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 10:04:42 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:43.745935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.77803778s","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-25T10:04:43.746057Z","caller":"traceutil/trace.go:171","msg":"trace[1894690623] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:913; }","duration":"2.778171183s","start":"2026-02-25T10:04:40.967868Z","end":"2026-02-25T10:04:43.74604Z","steps":["trace[1894690623] 'range keys from in-memory index tree' (duration: 2.77802171s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:43.833012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99995627s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T10:04:43.833094Z","caller":"traceutil/trace.go:171","msg":"trace[846195905] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000096812s","start":"2026-02-25T10:04:41.832979Z","end":"2026-02-25T10:04:43.833076Z","steps":["trace[846195905] 'agreement among raft nodes before linearized reading' (duration: 1.999960049s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:43.833142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:41.832963Z","time spent":"2.000169544s","remote":"127.0.0.1:40988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:04:44.272172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:37.271135Z","time spent":"7.001031267s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-25T10:04:44.67766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999886197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T10:04:44.677739Z","caller":"traceutil/trace.go:171","msg":"trace[1615413360] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00002282s","start":"2026-02-25T10:04:42.677698Z","end":"2026-02-25T10:04:44.677721Z","steps":["trace[1615413360] 'agreement among raft nodes before linearized reading' (duration: 1.999923108s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:44.67779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:42.677674Z","time spent":"2.000105442s","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:04:44.937632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:37.936754Z","time spent":"7.000871302s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-25T10:04:44.968439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000220284s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:04:44.968524Z","caller":"traceutil/trace.go:171","msg":"trace[1913073576] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000326177s","start":"2026-02-25T10:04:42.968178Z","end":"2026-02-25T10:04:44.968504Z","steps":["trace[1913073576] 'agreement among raft nodes before linearized reading' (duration: 2.000214554s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:44.968568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:42.968134Z","time spent":"2.000424619s","remote":"127.0.0.1:40988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 10:04:44 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:45.499703Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"7f0b9c9440007a18","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-25T10:04:46.59183Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"8.802690335s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T10:04:46.686292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00001294s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:04:46.686381Z","caller":"traceutil/trace.go:171","msg":"trace[713050441] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000149062s","start":"2026-02-25T10:04:44.686214Z","end":"2026-02-25T10:04:46.686363Z","steps":["trace[713050441] 'agreement among raft nodes before linearized reading' (duration: 2.00004557s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:46.686626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:44.686195Z","time spent":"2.000356747s","remote":"127.0.0.1:40988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 10:04:46 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:04:46.940155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.66281ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-25T10:04:47.357484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.818487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-02-25T10:04:47.35766Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"41.97µs","request":"header: lease_revoke:","response":"size:29","error":"lease not found"} {"level":"warn","ts":"2026-02-25T10:04:47.619349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.395263ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-25T10:04:47.619766Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"59.091µs","request":"header: lease_revoke:","response":"size:29","error":"lease not found"} {"level":"warn","ts":"2026-02-25T10:04:47.619848Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"11.02µs","request":"header: lease_revoke:","response":"size:29","error":"lease not found"} {"level":"warn","ts":"2026-02-25T10:04:47.620034Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"70.771µs","request":"header: lease_revoke:","response":"size:29","error":"lease not found"} {"level":"info","ts":"2026-02-25T10:04:47.620063Z","caller":"traceutil/trace.go:171","msg":"trace[509599146] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:947; }","duration":"10.573441247s","start":"2026-02-25T10:04:37.046594Z","end":"2026-02-25T10:04:47.620035Z","steps":["trace[509599146] 'read index received' (duration: 742.703194ms)","trace[509599146] 'applied index is now lower than readState.Index' (duration: 9.830734482s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:47.620189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"15.495281645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:04:47.620238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"16.969244803s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:04:47.620252Z","caller":"traceutil/trace.go:171","msg":"trace[355813430] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:0; response_revision:921; }","duration":"15.495381578s","start":"2026-02-25T10:04:32.124848Z","end":"2026-02-25T10:04:47.620229Z","steps":["trace[355813430] 'agreement among raft nodes before linearized reading' (duration: 15.495286816s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:47.620277Z","caller":"traceutil/trace.go:171","msg":"trace[1030514496] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:921; }","duration":"16.969300755s","start":"2026-02-25T10:04:30.650965Z","end":"2026-02-25T10:04:47.620266Z","steps":["trace[1030514496] 'agreement among raft nodes before linearized reading' (duration: 16.969210153s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:47.620258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.992885523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18977537bb0cbff7\" ","response":"range_response_count:1 size:774"} {"level":"warn","ts":"2026-02-25T10:04:47.620262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.608688851s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T10:04:47.620318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:30.650933Z","time spent":"16.969370326s","remote":"127.0.0.1:55840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:47.62032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:32.124829Z","time spent":"15.49547702s","remote":"127.0.0.1:41036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":29,"request content":"key:\"/registry/masterleases/162.253.55.36\" "} {"level":"info","ts":"2026-02-25T10:04:47.620342Z","caller":"traceutil/trace.go:171","msg":"trace[160270805] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:921; }","duration":"10.608822204s","start":"2026-02-25T10:04:37.011501Z","end":"2026-02-25T10:04:47.620323Z","steps":["trace[160270805] 'agreement among raft nodes before linearized reading' (duration: 10.60864417s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:47.620383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.703923538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T10:04:47.620396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:37.011478Z","time spent":"10.608900015s","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-25T10:04:47.62042Z","caller":"traceutil/trace.go:171","msg":"trace[840650712] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:921; }","duration":"10.70399911s","start":"2026-02-25T10:04:36.916413Z","end":"2026-02-25T10:04:47.620412Z","steps":["trace[840650712] 'agreement among raft nodes before linearized reading' (duration: 10.703941039s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:47.620454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:36.916392Z","time spent":"10.704053692s","remote":"127.0.0.1:41496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:47.620092Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"8.751µs","request":"header: lease_revoke:","response":"size:29","error":"lease not found"} {"level":"warn","ts":"2026-02-25T10:04:47.620687Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"36.431µs","request":"header: lease_revoke:","response":"size:29","error":"lease not found"} {"level":"warn","ts":"2026-02-25T10:04:47.620733Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"7f0b9c9440007a18","error":"lease not found"} {"level":"warn","ts":"2026-02-25T10:04:47.62082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.570245006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7036"} {"level":"info","ts":"2026-02-25T10:04:47.620848Z","caller":"traceutil/trace.go:171","msg":"trace[1838099749] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:921; }","duration":"10.570299427s","start":"2026-02-25T10:04:37.05054Z","end":"2026-02-25T10:04:47.620839Z","steps":["trace[1838099749] 'agreement among raft nodes before linearized reading' (duration: 10.570216715s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:47.620329Z","caller":"traceutil/trace.go:171","msg":"trace[789323303] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18977537bb0cbff7; range_end:; response_count:1; response_revision:921; }","duration":"12.993003896s","start":"2026-02-25T10:04:34.627308Z","end":"2026-02-25T10:04:47.620312Z","steps":["trace[789323303] 'agreement among raft nodes before linearized reading' (duration: 12.992798401s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:47.620882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:37.050523Z","time spent":"10.570353688s","remote":"127.0.0.1:41174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7060,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-25T10:04:47.620896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"14.798288898s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:04:47.620963Z","caller":"traceutil/trace.go:171","msg":"trace[88817316] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:921; }","duration":"14.798452272s","start":"2026-02-25T10:04:32.822495Z","end":"2026-02-25T10:04:47.620947Z","steps":["trace[88817316] 'agreement among raft nodes before linearized reading' (duration: 14.798338029s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:47.620977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.380915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:04:47.620982Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"7f0b9c9440007ab8","error":"lease not found"} {"level":"warn","ts":"2026-02-25T10:04:47.620969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:34.627287Z","time spent":"12.993630579s","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18977537bb0cbff7\" "} {"level":"warn","ts":"2026-02-25T10:04:47.621016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:32.822461Z","time spent":"14.798540014s","remote":"127.0.0.1:41268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-02-25T10:04:47.621049Z","caller":"traceutil/trace.go:171","msg":"trace[1567059477] transaction","detail":"{read_only:false; number_of_response:1; response_revision:921; }","duration":"3.344834211s","start":"2026-02-25T10:04:44.276205Z","end":"2026-02-25T10:04:47.62104Z","steps":["trace[1567059477] 'process raft request' (duration: 3.34434457s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:47.621089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.950169801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:04:47.621014Z","caller":"traceutil/trace.go:171","msg":"trace[380015398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:921; }","duration":"934.459607ms","start":"2026-02-25T10:04:46.686543Z","end":"2026-02-25T10:04:47.621003Z","steps":["trace[380015398] 'agreement among raft nodes before linearized reading' (duration: 934.398585ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:47.621124Z","caller":"traceutil/trace.go:171","msg":"trace[1068395673] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:921; }","duration":"7.950257922s","start":"2026-02-25T10:04:39.670858Z","end":"2026-02-25T10:04:47.621116Z","steps":["trace[1068395673] 'agreement among raft nodes before linearized reading' (duration: 7.950213501s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:47.621132Z","caller":"traceutil/trace.go:171","msg":"trace[2094597305] transaction","detail":"{read_only:false; number_of_response:1; response_revision:921; }","duration":"2.679021076s","start":"2026-02-25T10:04:44.942091Z","end":"2026-02-25T10:04:47.621112Z","steps":["trace[2094597305] 'process raft request' (duration: 2.678525385s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:47.621157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:39.670821Z","time spent":"7.950328914s","remote":"127.0.0.1:41226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:47.621156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:46.686522Z","time spent":"934.61316ms","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:04:47.62123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:44.942066Z","time spent":"2.679111079s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":571,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:04:47.621238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.382605695s","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":"warn","ts":"2026-02-25T10:04:47.621247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.742195234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:04:47.621291Z","caller":"traceutil/trace.go:171","msg":"trace[1898567821] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:921; }","duration":"8.382712347s","start":"2026-02-25T10:04:39.238568Z","end":"2026-02-25T10:04:47.62128Z","steps":["trace[1898567821] 'agreement among raft nodes before linearized reading' (duration: 8.382627115s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:47.621051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.311317346s","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-25T10:04:47.621309Z","caller":"traceutil/trace.go:171","msg":"trace[1349661963] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:921; }","duration":"9.742288986s","start":"2026-02-25T10:04:37.879001Z","end":"2026-02-25T10:04:47.62129Z","steps":["trace[1349661963] 'agreement among raft nodes before linearized reading' (duration: 9.742190874s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:47.621327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:39.238538Z","time spent":"8.382777419s","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:47.621365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:37.878984Z","time spent":"9.742367807s","remote":"127.0.0.1:41152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-02-25T10:04:47.621368Z","caller":"traceutil/trace.go:171","msg":"trace[399888574] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:921; }","duration":"7.311675143s","start":"2026-02-25T10:04:40.309666Z","end":"2026-02-25T10:04:47.621342Z","steps":["trace[399888574] 'agreement among raft nodes before linearized reading' (duration: 7.311360946s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:47.621438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:40.309637Z","time spent":"7.311780536s","remote":"127.0.0.1:41128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:47.62114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:44.276185Z","time spent":"3.344899752s","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":721,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:04:47.621307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.816802343s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:04:47.621673Z","caller":"traceutil/trace.go:171","msg":"trace[95471806] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:921; }","duration":"9.817198072s","start":"2026-02-25T10:04:37.804459Z","end":"2026-02-25T10:04:47.621657Z","steps":["trace[95471806] 'agreement among raft nodes before linearized reading' (duration: 9.816822274s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:47.621733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:37.804436Z","time spent":"9.817279824s","remote":"127.0.0.1:41176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:47.835706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.283798ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-25T10:04:47.916516Z","caller":"traceutil/trace.go:171","msg":"trace[795529795] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:971; }","duration":"290.56716ms","start":"2026-02-25T10:04:47.625926Z","end":"2026-02-25T10:04:47.916493Z","steps":["trace[795529795] 'read index received' (duration: 105.510746ms)","trace[795529795] 'applied index is now lower than readState.Index' (duration: 185.055464ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:04:47.916611Z","caller":"traceutil/trace.go:171","msg":"trace[339996593] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"288.228349ms","start":"2026-02-25T10:04:47.628374Z","end":"2026-02-25T10:04:47.916602Z","steps":["trace[339996593] 'process raft request' (duration: 288.067905ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:47.916661Z","caller":"traceutil/trace.go:171","msg":"trace[1356094196] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"292.75768ms","start":"2026-02-25T10:04:47.623844Z","end":"2026-02-25T10:04:47.916602Z","steps":["trace[1356094196] 'process raft request' (duration: 292.443043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:47.916831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.875247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:1 size:686"} {"level":"info","ts":"2026-02-25T10:04:47.916884Z","caller":"traceutil/trace.go:171","msg":"trace[1978786039] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:1; response_revision:923; }","duration":"290.950469ms","start":"2026-02-25T10:04:47.625919Z","end":"2026-02-25T10:04:47.91687Z","steps":["trace[1978786039] 'agreement among raft nodes before linearized reading' (duration: 290.699133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:47.928072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.490357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:04:47.928116Z","caller":"traceutil/trace.go:171","msg":"trace[2127432677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"298.551579ms","start":"2026-02-25T10:04:47.629553Z","end":"2026-02-25T10:04:47.928104Z","steps":["trace[2127432677] 'agreement among raft nodes before linearized reading' (duration: 298.461687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:47.928133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.705179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-25T10:04:47.928185Z","caller":"traceutil/trace.go:171","msg":"trace[1579067471] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:924; }","duration":"301.78072ms","start":"2026-02-25T10:04:47.626393Z","end":"2026-02-25T10:04:47.928174Z","steps":["trace[1579067471] 'agreement among raft nodes before linearized reading' (duration: 301.642217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:47.928214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:47.626386Z","time spent":"301.823191ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":560,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-02-25T10:04:48.113236Z","caller":"traceutil/trace.go:171","msg":"trace[688874106] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"185.184468ms","start":"2026-02-25T10:04:47.928002Z","end":"2026-02-25T10:04:48.113187Z","steps":["trace[688874106] 'read index received' (duration: 101.055717ms)","trace[688874106] 'applied index is now lower than readState.Index' (duration: 84.127101ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:48.113586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.683966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:04:48.113681Z","caller":"traceutil/trace.go:171","msg":"trace[82770503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"184.803519ms","start":"2026-02-25T10:04:47.928863Z","end":"2026-02-25T10:04:48.113666Z","steps":["trace[82770503] 'agreement among raft nodes before linearized reading' (duration: 184.663686ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:48.113584Z","caller":"traceutil/trace.go:171","msg":"trace[1412827412] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"191.383265ms","start":"2026-02-25T10:04:47.922171Z","end":"2026-02-25T10:04:48.113554Z","steps":["trace[1412827412] 'process raft request' (duration: 106.931007ms)","trace[1412827412] 'compare' (duration: 83.945367ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:48.113728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.856078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-02-25T10:04:48.113748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.258114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"warn","ts":"2026-02-25T10:04:48.11376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.363205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7228"} {"level":"info","ts":"2026-02-25T10:04:48.113781Z","caller":"traceutil/trace.go:171","msg":"trace[510545868] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:925; }","duration":"183.93533ms","start":"2026-02-25T10:04:47.929831Z","end":"2026-02-25T10:04:48.113766Z","steps":["trace[510545868] 'agreement among raft nodes before linearized reading' (duration: 183.820678ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:48.113808Z","caller":"traceutil/trace.go:171","msg":"trace[803836296] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:925; }","duration":"183.358316ms","start":"2026-02-25T10:04:47.930436Z","end":"2026-02-25T10:04:48.113794Z","steps":["trace[803836296] 'agreement among raft nodes before linearized reading' (duration: 183.180432ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:48.113812Z","caller":"traceutil/trace.go:171","msg":"trace[1030913015] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:925; }","duration":"191.419816ms","start":"2026-02-25T10:04:47.922381Z","end":"2026-02-25T10:04:48.113801Z","steps":["trace[1030913015] 'agreement among raft nodes before linearized reading' (duration: 191.266213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:48.114204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.51194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18977537bb0cbff7\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-25T10:04:48.114287Z","caller":"traceutil/trace.go:171","msg":"trace[1476704425] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18977537bb0cbff7; range_end:; response_count:1; response_revision:925; }","duration":"192.721694ms","start":"2026-02-25T10:04:47.921547Z","end":"2026-02-25T10:04:48.114269Z","steps":["trace[1476704425] 'agreement among raft nodes before linearized reading' (duration: 191.871636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:48.37586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.100973ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:04:48.375984Z","caller":"traceutil/trace.go:171","msg":"trace[1725967872] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"257.097517ms","start":"2026-02-25T10:04:48.118878Z","end":"2026-02-25T10:04:48.375975Z","steps":["trace[1725967872] 'process raft request' (duration: 257.057576ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:48.375975Z","caller":"traceutil/trace.go:171","msg":"trace[85169596] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"257.872904ms","start":"2026-02-25T10:04:48.118061Z","end":"2026-02-25T10:04:48.375934Z","steps":["trace[85169596] 'process raft request' (duration: 105.629278ms)","trace[85169596] 'compare' (duration: 152.008161ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:04:48.376158Z","caller":"traceutil/trace.go:171","msg":"trace[1899346918] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"257.768392ms","start":"2026-02-25T10:04:48.118381Z","end":"2026-02-25T10:04:48.376149Z","steps":["trace[1899346918] 'read index received' (duration: 105.322361ms)","trace[1899346918] 'applied index is now lower than readState.Index' (duration: 152.445251ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:48.376307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.921845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7228"} {"level":"info","ts":"2026-02-25T10:04:48.376331Z","caller":"traceutil/trace.go:171","msg":"trace[1972243200] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:927; }","duration":"257.965135ms","start":"2026-02-25T10:04:48.11836Z","end":"2026-02-25T10:04:48.376325Z","steps":["trace[1972243200] 'agreement among raft nodes before linearized reading' (duration: 257.873683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:48.376957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.323144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T10:04:48.376991Z","caller":"traceutil/trace.go:171","msg":"trace[1345678668] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:927; }","duration":"258.374075ms","start":"2026-02-25T10:04:48.118609Z","end":"2026-02-25T10:04:48.376983Z","steps":["trace[1345678668] 'agreement among raft nodes before linearized reading' (duration: 257.69053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:48.916801Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582828103531272,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:49.092695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.908765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:04:49.092878Z","caller":"traceutil/trace.go:171","msg":"trace[622099017] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:982; }","duration":"676.995943ms","start":"2026-02-25T10:04:48.415857Z","end":"2026-02-25T10:04:49.092853Z","steps":["trace[622099017] 'read index received' (duration: 543.821162ms)","trace[622099017] 'applied index is now lower than readState.Index' (duration: 133.172831ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:49.093082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.214348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T10:04:49.093116Z","caller":"traceutil/trace.go:171","msg":"trace[503594240] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:931; }","duration":"677.28887ms","start":"2026-02-25T10:04:48.415819Z","end":"2026-02-25T10:04:49.093107Z","steps":["trace[503594240] 'agreement among raft nodes before linearized reading' (duration: 677.097636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:49.093155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:48.415802Z","time spent":"677.344091ms","remote":"127.0.0.1:41036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-02-25T10:04:49.093289Z","caller":"traceutil/trace.go:171","msg":"trace[87102890] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"678.085127ms","start":"2026-02-25T10:04:48.415167Z","end":"2026-02-25T10:04:49.093252Z","steps":["trace[87102890] 'process raft request' (duration: 544.561318ms)","trace[87102890] 'compare' (duration: 132.598648ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:49.093508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:48.415156Z","time spent":"678.280201ms","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":857,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:04:49.773896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.546535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:04:49.77402Z","caller":"traceutil/trace.go:171","msg":"trace[1063911928] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:983; }","duration":"158.7032ms","start":"2026-02-25T10:04:49.6153Z","end":"2026-02-25T10:04:49.774004Z","steps":["trace[1063911928] 'read index received' (duration: 55.572µs)","trace[1063911928] 'applied index is now lower than readState.Index' (duration: 158.645798ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:49.774109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.814972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:04:49.774141Z","caller":"traceutil/trace.go:171","msg":"trace[675636798] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"158.883313ms","start":"2026-02-25T10:04:49.615249Z","end":"2026-02-25T10:04:49.774132Z","steps":["trace[675636798] 'agreement among raft nodes before linearized reading' (duration: 158.801762ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:49.774454Z","caller":"traceutil/trace.go:171","msg":"trace[1867380163] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"675.49337ms","start":"2026-02-25T10:04:49.098933Z","end":"2026-02-25T10:04:49.774426Z","steps":["trace[1867380163] 'process raft request' (duration: 297.326491ms)","trace[1867380163] 'compare' (duration: 377.141366ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:49.774601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:49.098917Z","time spent":"675.607852ms","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:04:50.009703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.575982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:04:50.009816Z","caller":"traceutil/trace.go:171","msg":"trace[1429130655] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"230.044345ms","start":"2026-02-25T10:04:49.779752Z","end":"2026-02-25T10:04:50.009796Z","steps":["trace[1429130655] 'process raft request' (duration: 104.296979ms)","trace[1429130655] 'compare' (duration: 125.4537ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:04:50.2389Z","caller":"traceutil/trace.go:171","msg":"trace[439478794] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"182.235352ms","start":"2026-02-25T10:04:50.056639Z","end":"2026-02-25T10:04:50.238874Z","steps":["trace[439478794] 'process raft request' (duration: 133.339564ms)","trace[439478794] 'compare' (duration: 48.784385ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:50.623855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.55942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:04:50.62395Z","caller":"traceutil/trace.go:171","msg":"trace[178395250] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"326.285354ms","start":"2026-02-25T10:04:50.297647Z","end":"2026-02-25T10:04:50.623933Z","steps":["trace[178395250] 'process raft request' (duration: 44.560471ms)","trace[178395250] 'compare' (duration: 281.464127ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:50.624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:50.297634Z","time spent":"326.346036ms","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T10:04:50.95464Z","caller":"traceutil/trace.go:171","msg":"trace[928758063] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"254.248904ms","start":"2026-02-25T10:04:50.700373Z","end":"2026-02-25T10:04:50.954622Z","steps":["trace[928758063] 'process raft request' (duration: 254.115631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:52.40959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.63399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:04:52.409674Z","caller":"traceutil/trace.go:171","msg":"trace[954521618] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"826.20478ms","start":"2026-02-25T10:04:51.583455Z","end":"2026-02-25T10:04:52.40966Z","steps":["trace[954521618] 'read index received' (duration: 58.831µs)","trace[954521618] 'applied index is now lower than readState.Index' (duration: 826.144569ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:04:52.409755Z","caller":"traceutil/trace.go:171","msg":"trace[1996890736] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"1.401617225s","start":"2026-02-25T10:04:51.008126Z","end":"2026-02-25T10:04:52.409743Z","steps":["trace[1996890736] 'process raft request' (duration: 458.757551ms)","trace[1996890736] 'compare' (duration: 942.328581ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:52.409842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:51.008102Z","time spent":"1.401679547s","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:04:52.410176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.716351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-sdnk7\" ","response":"range_response_count:1 size:4624"} {"level":"info","ts":"2026-02-25T10:04:52.410218Z","caller":"traceutil/trace.go:171","msg":"trace[1118310528] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-sdnk7; range_end:; response_count:1; response_revision:941; }","duration":"826.796373ms","start":"2026-02-25T10:04:51.583411Z","end":"2026-02-25T10:04:52.410207Z","steps":["trace[1118310528] 'agreement among raft nodes before linearized reading' (duration: 826.625629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:52.41025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:51.583387Z","time spent":"826.854544ms","remote":"127.0.0.1:41174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4648,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-sdnk7\" "} {"level":"warn","ts":"2026-02-25T10:04:52.410355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.729967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:04:52.41041Z","caller":"traceutil/trace.go:171","msg":"trace[1825719064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"797.820729ms","start":"2026-02-25T10:04:51.612573Z","end":"2026-02-25T10:04:52.410394Z","steps":["trace[1825719064] 'agreement among raft nodes before linearized reading' (duration: 797.729717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:52.410459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:51.612556Z","time spent":"797.89201ms","remote":"127.0.0.1:40988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T10:04:52.604438Z","caller":"traceutil/trace.go:171","msg":"trace[1059564832] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"187.06622ms","start":"2026-02-25T10:04:52.41734Z","end":"2026-02-25T10:04:52.604406Z","steps":["trace[1059564832] 'process raft request' (duration: 112.675826ms)","trace[1059564832] 'compare' (duration: 74.256781ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:52.852946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.533165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:04:52.853047Z","caller":"traceutil/trace.go:171","msg":"trace[999798174] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"244.68692ms","start":"2026-02-25T10:04:52.608341Z","end":"2026-02-25T10:04:52.853028Z","steps":["trace[999798174] 'read index received' (duration: 103.9039ms)","trace[999798174] 'applied index is now lower than readState.Index' (duration: 140.78176ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:52.853467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.10769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18977537bb0cbff7\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-25T10:04:52.853548Z","caller":"traceutil/trace.go:171","msg":"trace[221992995] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18977537bb0cbff7; range_end:; response_count:1; response_revision:943; }","duration":"245.199452ms","start":"2026-02-25T10:04:52.608326Z","end":"2026-02-25T10:04:52.853525Z","steps":["trace[221992995] 'agreement among raft nodes before linearized reading' (duration: 244.927366ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:52.853674Z","caller":"traceutil/trace.go:171","msg":"trace[1719089174] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"435.515163ms","start":"2026-02-25T10:04:52.418141Z","end":"2026-02-25T10:04:52.853656Z","steps":["trace[1719089174] 'process raft request' (duration: 294.193621ms)","trace[1719089174] 'compare' (duration: 140.383012ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:52.853752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:52.418121Z","time spent":"435.591195ms","remote":"127.0.0.1:41174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:04:52.853376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.401881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:04:52.854081Z","caller":"traceutil/trace.go:171","msg":"trace[1752410368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:943; }","duration":"239.104647ms","start":"2026-02-25T10:04:52.614943Z","end":"2026-02-25T10:04:52.854048Z","steps":["trace[1752410368] 'agreement among raft nodes before linearized reading' (duration: 238.401331ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:53.075523Z","caller":"traceutil/trace.go:171","msg":"trace[2080004729] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"164.922737ms","start":"2026-02-25T10:04:52.910577Z","end":"2026-02-25T10:04:53.075499Z","steps":["trace[2080004729] 'process raft request' (duration: 73.884254ms)","trace[2080004729] 'compare' (duration: 90.935801ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:53.288919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.280574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:04:53.289186Z","caller":"traceutil/trace.go:171","msg":"trace[1751196536] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"207.889333ms","start":"2026-02-25T10:04:53.081271Z","end":"2026-02-25T10:04:53.289161Z","steps":["trace[1751196536] 'process raft request' (duration: 105.2717ms)","trace[1751196536] 'compare' (duration: 102.09478ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:04:53.626999Z","caller":"traceutil/trace.go:171","msg":"trace[532871645] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"290.797406ms","start":"2026-02-25T10:04:53.33617Z","end":"2026-02-25T10:04:53.626968Z","steps":["trace[532871645] 'process raft request' (duration: 195.120859ms)","trace[532871645] 'compare' (duration: 95.551944ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:04:53.98099Z","caller":"traceutil/trace.go:171","msg":"trace[669275286] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"275.086638ms","start":"2026-02-25T10:04:53.705882Z","end":"2026-02-25T10:04:53.980969Z","steps":["trace[669275286] 'process raft request' (duration: 274.931293ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:08.917406Z","caller":"traceutil/trace.go:171","msg":"trace[1902705282] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"104.021892ms","start":"2026-02-25T10:05:08.813356Z","end":"2026-02-25T10:05:08.917378Z","steps":["trace[1902705282] 'process raft request' (duration: 103.847109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:09.079147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.375509ms","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-25T10:05:09.079294Z","caller":"traceutil/trace.go:171","msg":"trace[1398938160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1059; }","duration":"112.566863ms","start":"2026-02-25T10:05:08.966697Z","end":"2026-02-25T10:05:09.079264Z","steps":["trace[1398938160] 'range keys from in-memory index tree' (duration: 112.055871ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:09.079649Z","caller":"traceutil/trace.go:171","msg":"trace[1384298286] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"112.644445ms","start":"2026-02-25T10:05:08.966976Z","end":"2026-02-25T10:05:09.07962Z","steps":["trace[1384298286] 'process raft request' (duration: 35.785926ms)","trace[1384298286] 'compare' (duration: 76.263516ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:10.513221Z","caller":"traceutil/trace.go:171","msg":"trace[2035135864] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"119.991548ms","start":"2026-02-25T10:05:10.393205Z","end":"2026-02-25T10:05:10.513197Z","steps":["trace[2035135864] 'process raft request' (duration: 119.857335ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:11.327367Z","caller":"traceutil/trace.go:171","msg":"trace[300736302] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1121; }","duration":"201.867829ms","start":"2026-02-25T10:05:11.125474Z","end":"2026-02-25T10:05:11.327342Z","steps":["trace[300736302] 'read index received' (duration: 201.703995ms)","trace[300736302] 'applied index is now lower than readState.Index' (duration: 163.054µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:11.3275Z","caller":"traceutil/trace.go:171","msg":"trace[2028493835] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"238.625375ms","start":"2026-02-25T10:05:11.088844Z","end":"2026-02-25T10:05:11.327469Z","steps":["trace[2028493835] 'process raft request' (duration: 238.321809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:11.327728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.448343ms","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-25T10:05:11.327786Z","caller":"traceutil/trace.go:171","msg":"trace[809161310] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1066; }","duration":"149.558866ms","start":"2026-02-25T10:05:11.178212Z","end":"2026-02-25T10:05:11.327771Z","steps":["trace[809161310] 'agreement among raft nodes before linearized reading' (duration: 149.422493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:11.328417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.936712ms","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-25T10:05:11.328465Z","caller":"traceutil/trace.go:171","msg":"trace[1156062772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1066; }","duration":"203.030044ms","start":"2026-02-25T10:05:11.125424Z","end":"2026-02-25T10:05:11.328454Z","steps":["trace[1156062772] 'agreement among raft nodes before linearized reading' (duration: 202.040882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:11.799882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.594264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:11.800125Z","caller":"traceutil/trace.go:171","msg":"trace[962605959] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"466.932951ms","start":"2026-02-25T10:05:11.333168Z","end":"2026-02-25T10:05:11.800101Z","steps":["trace[962605959] 'process raft request' (duration: 187.922858ms)","trace[962605959] 'compare' (duration: 278.474472ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:11.800227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:11.333148Z","time spent":"467.032933ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:11.800283Z","caller":"traceutil/trace.go:171","msg":"trace[1054181997] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1122; }","duration":"325.759462ms","start":"2026-02-25T10:05:11.474472Z","end":"2026-02-25T10:05:11.800232Z","steps":["trace[1054181997] 'read index received' (duration: 46.546625ms)","trace[1054181997] 'applied index is now lower than readState.Index' (duration: 279.211107ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:11.800376Z","caller":"traceutil/trace.go:171","msg":"trace[222686686] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"466.632354ms","start":"2026-02-25T10:05:11.333701Z","end":"2026-02-25T10:05:11.800333Z","steps":["trace[222686686] 'process raft request' (duration: 466.314908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:11.800555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:11.333676Z","time spent":"466.788949ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:11.800727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.252205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T10:05:11.800797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.339302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-25T10:05:11.800814Z","caller":"traceutil/trace.go:171","msg":"trace[1591314736] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1068; }","duration":"326.369247ms","start":"2026-02-25T10:05:11.474429Z","end":"2026-02-25T10:05:11.800798Z","steps":["trace[1591314736] 'agreement among raft nodes before linearized reading' (duration: 326.06014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:11.800858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:11.47441Z","time spent":"326.437889ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:05:11.80088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.946885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:11.800912Z","caller":"traceutil/trace.go:171","msg":"trace[1211749610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1068; }","duration":"186.017257ms","start":"2026-02-25T10:05:11.614884Z","end":"2026-02-25T10:05:11.800902Z","steps":["trace[1211749610] 'agreement among raft nodes before linearized reading' (duration: 185.946585ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:11.800851Z","caller":"traceutil/trace.go:171","msg":"trace[1557594475] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1068; }","duration":"235.477165ms","start":"2026-02-25T10:05:11.565357Z","end":"2026-02-25T10:05:11.800834Z","steps":["trace[1557594475] 'agreement among raft nodes before linearized reading' (duration: 235.295651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:14.432458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.897968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-25T10:05:14.432562Z","caller":"traceutil/trace.go:171","msg":"trace[1058095188] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:1081; }","duration":"121.026921ms","start":"2026-02-25T10:05:14.311504Z","end":"2026-02-25T10:05:14.432531Z","steps":["trace[1058095188] 'range keys from in-memory index tree' (duration: 120.729665ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:14.654264Z","caller":"traceutil/trace.go:171","msg":"trace[1356618090] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"214.187421ms","start":"2026-02-25T10:05:14.440045Z","end":"2026-02-25T10:05:14.654233Z","steps":["trace[1356618090] 'process raft request' (duration: 213.965287ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:14.654365Z","caller":"traceutil/trace.go:171","msg":"trace[285782353] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"214.046969ms","start":"2026-02-25T10:05:14.440285Z","end":"2026-02-25T10:05:14.654332Z","steps":["trace[285782353] 'process raft request' (duration: 213.872825ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:14.654445Z","caller":"traceutil/trace.go:171","msg":"trace[1943565870] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"213.736592ms","start":"2026-02-25T10:05:14.440689Z","end":"2026-02-25T10:05:14.654426Z","steps":["trace[1943565870] 'process raft request' (duration: 213.528867ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:14.654529Z","caller":"traceutil/trace.go:171","msg":"trace[49898173] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1139; }","duration":"207.652337ms","start":"2026-02-25T10:05:14.446851Z","end":"2026-02-25T10:05:14.654503Z","steps":["trace[49898173] 'read index received' (duration: 67.875739ms)","trace[49898173] 'applied index is now lower than readState.Index' (duration: 139.775388ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:14.654656Z","caller":"traceutil/trace.go:171","msg":"trace[300934503] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"210.885828ms","start":"2026-02-25T10:05:14.443759Z","end":"2026-02-25T10:05:14.654645Z","steps":["trace[300934503] 'process raft request' (duration: 210.581962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:14.654771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.435281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T10:05:14.654825Z","caller":"traceutil/trace.go:171","msg":"trace[1081653112] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:1086; }","duration":"193.513673ms","start":"2026-02-25T10:05:14.461297Z","end":"2026-02-25T10:05:14.654811Z","steps":["trace[1081653112] 'agreement among raft nodes before linearized reading' (duration: 193.409951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:14.6549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.908688ms","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-25T10:05:14.654955Z","caller":"traceutil/trace.go:171","msg":"trace[1533374842] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1086; }","duration":"117.019431ms","start":"2026-02-25T10:05:14.537923Z","end":"2026-02-25T10:05:14.654943Z","steps":["trace[1533374842] 'agreement among raft nodes before linearized reading' (duration: 116.863098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:14.654958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.097477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:7008"} {"level":"info","ts":"2026-02-25T10:05:14.65502Z","caller":"traceutil/trace.go:171","msg":"trace[673232952] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:1086; }","duration":"208.171629ms","start":"2026-02-25T10:05:14.446824Z","end":"2026-02-25T10:05:14.654996Z","steps":["trace[673232952] 'agreement among raft nodes before linearized reading' (duration: 208.032745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:14.655041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.557562ms","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-25T10:05:14.65507Z","caller":"traceutil/trace.go:171","msg":"trace[35650632] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1086; }","duration":"192.597493ms","start":"2026-02-25T10:05:14.462463Z","end":"2026-02-25T10:05:14.655061Z","steps":["trace[35650632] 'agreement among raft nodes before linearized reading' (duration: 192.49796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.260474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.249077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:15.260681Z","caller":"traceutil/trace.go:171","msg":"trace[410753479] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"602.715901ms","start":"2026-02-25T10:05:14.657914Z","end":"2026-02-25T10:05:15.26063Z","steps":["trace[410753479] 'process raft request' (duration: 114.006524ms)","trace[410753479] 'compare' (duration: 488.107813ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:15.260789Z","caller":"traceutil/trace.go:171","msg":"trace[2104887849] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"601.422483ms","start":"2026-02-25T10:05:14.659337Z","end":"2026-02-25T10:05:15.260759Z","steps":["trace[2104887849] 'read index received' (duration: 112.571283ms)","trace[2104887849] 'applied index is now lower than readState.Index' (duration: 488.84854ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:15.260867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.657897Z","time spent":"602.851964ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:15.260876Z","caller":"traceutil/trace.go:171","msg":"trace[756997716] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"600.8586ms","start":"2026-02-25T10:05:14.659997Z","end":"2026-02-25T10:05:15.260855Z","steps":["trace[756997716] 'process raft request' (duration: 600.650085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.260978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.65998Z","time spent":"600.945533ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:15.261024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.391959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-25T10:05:15.261032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.669378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-25T10:05:15.261098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.045797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5742"} {"level":"info","ts":"2026-02-25T10:05:15.261115Z","caller":"traceutil/trace.go:171","msg":"trace[1342282003] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:1089; }","duration":"601.792811ms","start":"2026-02-25T10:05:14.659302Z","end":"2026-02-25T10:05:15.261095Z","steps":["trace[1342282003] 'agreement among raft nodes before linearized reading' (duration: 601.577557ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.261144Z","caller":"traceutil/trace.go:171","msg":"trace[1821127051] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1089; }","duration":"495.094478ms","start":"2026-02-25T10:05:14.766036Z","end":"2026-02-25T10:05:15.261131Z","steps":["trace[1821127051] 'agreement among raft nodes before linearized reading' (duration: 494.951285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.261173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.766033Z","time spent":"495.133019ms","remote":"127.0.0.1:41172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5766,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-25T10:05:15.261171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.65928Z","time spent":"601.881503ms","remote":"127.0.0.1:41190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-02-25T10:05:15.261219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.564109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-hqqrr\" ","response":"range_response_count:1 size:4847"} {"level":"warn","ts":"2026-02-25T10:05:15.261236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.502717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-8dcbf\" ","response":"range_response_count:1 size:4847"} {"level":"info","ts":"2026-02-25T10:05:15.261288Z","caller":"traceutil/trace.go:171","msg":"trace[1411630301] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-8dcbf; range_end:; response_count:1; response_revision:1089; }","duration":"495.557738ms","start":"2026-02-25T10:05:14.765716Z","end":"2026-02-25T10:05:15.261274Z","steps":["trace[1411630301] 'agreement among raft nodes before linearized reading' (duration: 495.403955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.26132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.687721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-lbk7c\" ","response":"range_response_count:1 size:13268"} {"level":"warn","ts":"2026-02-25T10:05:15.261333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.765714Z","time spent":"495.609779ms","remote":"127.0.0.1:41174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4871,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-8dcbf\" "} {"level":"info","ts":"2026-02-25T10:05:15.261356Z","caller":"traceutil/trace.go:171","msg":"trace[299867482] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-lbk7c; range_end:; response_count:1; response_revision:1089; }","duration":"495.729582ms","start":"2026-02-25T10:05:14.765619Z","end":"2026-02-25T10:05:15.261349Z","steps":["trace[299867482] 'agreement among raft nodes before linearized reading' (duration: 495.65109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.261397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.765616Z","time spent":"495.771234ms","remote":"127.0.0.1:41174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":13292,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-lbk7c\" "} {"level":"warn","ts":"2026-02-25T10:05:15.261406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.078737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-25T10:05:15.261436Z","caller":"traceutil/trace.go:171","msg":"trace[324346087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1089; }","duration":"190.109178ms","start":"2026-02-25T10:05:15.071319Z","end":"2026-02-25T10:05:15.261428Z","steps":["trace[324346087] 'agreement among raft nodes before linearized reading' (duration: 190.025726ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.261323Z","caller":"traceutil/trace.go:171","msg":"trace[549038681] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"596.607816ms","start":"2026-02-25T10:05:14.664705Z","end":"2026-02-25T10:05:15.261312Z","steps":["trace[549038681] 'process raft request' (duration: 596.023373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.261577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.664696Z","time spent":"596.81551ms","remote":"127.0.0.1:41174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6783,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:15.261584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.027299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/ceph-csi-rbd-nodeplugin-http-metrics\" ","response":"range_response_count:1 size:1189"} {"level":"warn","ts":"2026-02-25T10:05:15.261609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.245061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4687"} {"level":"warn","ts":"2026-02-25T10:05:15.261476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.478586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2896"} {"level":"info","ts":"2026-02-25T10:05:15.261637Z","caller":"traceutil/trace.go:171","msg":"trace[1064306202] range","detail":"{range_begin:/registry/services/specs/kube-system/ceph-csi-rbd-nodeplugin-http-metrics; range_end:; response_count:1; response_revision:1089; }","duration":"496.108161ms","start":"2026-02-25T10:05:14.765516Z","end":"2026-02-25T10:05:15.261625Z","steps":["trace[1064306202] 'agreement among raft nodes before linearized reading' (duration: 495.942127ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.261657Z","caller":"traceutil/trace.go:171","msg":"trace[1538002157] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:1089; }","duration":"495.299053ms","start":"2026-02-25T10:05:14.766349Z","end":"2026-02-25T10:05:15.261648Z","steps":["trace[1538002157] 'agreement among raft nodes before linearized reading' (duration: 495.170341ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.261663Z","caller":"traceutil/trace.go:171","msg":"trace[1105714889] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1089; }","duration":"495.672832ms","start":"2026-02-25T10:05:14.765981Z","end":"2026-02-25T10:05:15.261654Z","steps":["trace[1105714889] 'agreement among raft nodes before linearized reading' (duration: 495.447177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.261683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.766344Z","time spent":"495.331264ms","remote":"127.0.0.1:41462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":4711,"request content":"key:\"/registry/deployments/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-02-25T10:05:15.261679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.270952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:11068"} {"level":"warn","ts":"2026-02-25T10:05:15.261691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.765979Z","time spent":"495.705492ms","remote":"127.0.0.1:41480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2920,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-02-25T10:05:15.26169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.765502Z","time spent":"496.171592ms","remote":"127.0.0.1:41176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1213,"request content":"key:\"/registry/services/specs/kube-system/ceph-csi-rbd-nodeplugin-http-metrics\" "} {"level":"info","ts":"2026-02-25T10:05:15.261726Z","caller":"traceutil/trace.go:171","msg":"trace[462108680] range","detail":"{range_begin:/registry/deployments/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:1089; }","duration":"495.320374ms","start":"2026-02-25T10:05:14.766391Z","end":"2026-02-25T10:05:15.261712Z","steps":["trace[462108680] 'agreement among raft nodes before linearized reading' (duration: 495.107739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.261767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.766389Z","time spent":"495.367675ms","remote":"127.0.0.1:41462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":11092,"request content":"key:\"/registry/deployments/kube-system/ceph-csi-rbd-provisioner\" "} {"level":"warn","ts":"2026-02-25T10:05:15.261887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.797363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:9307"} {"level":"warn","ts":"2026-02-25T10:05:15.261591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.62569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14326"} {"level":"info","ts":"2026-02-25T10:05:15.261917Z","caller":"traceutil/trace.go:171","msg":"trace[190761215] range","detail":"{range_begin:/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:1089; }","duration":"495.860805ms","start":"2026-02-25T10:05:14.766048Z","end":"2026-02-25T10:05:15.261909Z","steps":["trace[190761215] 'agreement among raft nodes before linearized reading' (duration: 495.781333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.261943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.766029Z","time spent":"495.907857ms","remote":"127.0.0.1:41480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":9331,"request content":"key:\"/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin\" "} {"level":"info","ts":"2026-02-25T10:05:15.26194Z","caller":"traceutil/trace.go:171","msg":"trace[1295038276] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:1089; }","duration":"495.977287ms","start":"2026-02-25T10:05:14.76595Z","end":"2026-02-25T10:05:15.261927Z","steps":["trace[1295038276] 'agreement among raft nodes before linearized reading' (duration: 495.552588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.26198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.765947Z","time spent":"496.019828ms","remote":"127.0.0.1:41480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":14350,"request content":"key:\"/registry/daemonsets/kube-system/cilium\" "} {"level":"warn","ts":"2026-02-25T10:05:15.262101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.042489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-dns\" ","response":"range_response_count:1 size:1211"} {"level":"info","ts":"2026-02-25T10:05:15.262127Z","caller":"traceutil/trace.go:171","msg":"trace[1948519325] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-dns; range_end:; response_count:1; response_revision:1089; }","duration":"496.105421ms","start":"2026-02-25T10:05:14.766014Z","end":"2026-02-25T10:05:15.262119Z","steps":["trace[1948519325] 'agreement among raft nodes before linearized reading' (duration: 496.05074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.26215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.765993Z","time spent":"496.150671ms","remote":"127.0.0.1:41176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1235,"request content":"key:\"/registry/services/specs/kube-system/kube-dns\" "} {"level":"warn","ts":"2026-02-25T10:05:15.262145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.208303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-02-25T10:05:15.262194Z","caller":"traceutil/trace.go:171","msg":"trace[937583023] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:1089; }","duration":"496.264194ms","start":"2026-02-25T10:05:14.765917Z","end":"2026-02-25T10:05:15.262182Z","steps":["trace[937583023] 'agreement among raft nodes before linearized reading' (duration: 496.134091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.262234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.765914Z","time spent":"496.309175ms","remote":"127.0.0.1:41462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4221,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-02-25T10:05:15.261101Z","caller":"traceutil/trace.go:171","msg":"trace[310303452] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:1089; }","duration":"600.496831ms","start":"2026-02-25T10:05:14.660586Z","end":"2026-02-25T10:05:15.261083Z","steps":["trace[310303452] 'agreement among raft nodes before linearized reading' (duration: 600.358098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.262275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.660577Z","time spent":"601.688538ms","remote":"127.0.0.1:41190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-02-25T10:05:15.261253Z","caller":"traceutil/trace.go:171","msg":"trace[1173299239] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-hqqrr; range_end:; response_count:1; response_revision:1089; }","duration":"495.60401ms","start":"2026-02-25T10:05:14.765641Z","end":"2026-02-25T10:05:15.261245Z","steps":["trace[1173299239] 'agreement among raft nodes before linearized reading' (duration: 495.486497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.262435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.765639Z","time spent":"496.781645ms","remote":"127.0.0.1:41174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4871,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-hqqrr\" "} {"level":"warn","ts":"2026-02-25T10:05:15.262449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.195923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/ceph-csi-rbd-provisioner-http-metrics\" ","response":"range_response_count:1 size:1191"} {"level":"info","ts":"2026-02-25T10:05:15.26281Z","caller":"traceutil/trace.go:171","msg":"trace[1017615462] range","detail":"{range_begin:/registry/services/specs/kube-system/ceph-csi-rbd-provisioner-http-metrics; range_end:; response_count:1; response_revision:1089; }","duration":"496.55662ms","start":"2026-02-25T10:05:14.766229Z","end":"2026-02-25T10:05:15.262786Z","steps":["trace[1017615462] 'agreement among raft nodes before linearized reading' (duration: 495.306093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.262871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.766223Z","time spent":"496.627853ms","remote":"127.0.0.1:41176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":1215,"request content":"key:\"/registry/services/specs/kube-system/ceph-csi-rbd-provisioner-http-metrics\" "} {"level":"info","ts":"2026-02-25T10:05:15.568947Z","caller":"traceutil/trace.go:171","msg":"trace[150936543] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"298.047488ms","start":"2026-02-25T10:05:15.270875Z","end":"2026-02-25T10:05:15.568922Z","steps":["trace[150936543] 'process raft request' (duration: 297.014424ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.569005Z","caller":"traceutil/trace.go:171","msg":"trace[862764167] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"297.368342ms","start":"2026-02-25T10:05:15.271611Z","end":"2026-02-25T10:05:15.56898Z","steps":["trace[862764167] 'process raft request' (duration: 297.2442ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.569123Z","caller":"traceutil/trace.go:171","msg":"trace[897537482] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"297.127087ms","start":"2026-02-25T10:05:15.27197Z","end":"2026-02-25T10:05:15.569097Z","steps":["trace[897537482] 'process raft request' (duration: 296.949913ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.570231Z","caller":"traceutil/trace.go:171","msg":"trace[1458407835] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1148; }","duration":"186.989308ms","start":"2026-02-25T10:05:15.383228Z","end":"2026-02-25T10:05:15.570218Z","steps":["trace[1458407835] 'read index received' (duration: 184.580395ms)","trace[1458407835] 'applied index is now lower than readState.Index' (duration: 2.407723ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:15.570404Z","caller":"traceutil/trace.go:171","msg":"trace[2043680661] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"292.663597ms","start":"2026-02-25T10:05:15.277728Z","end":"2026-02-25T10:05:15.570392Z","steps":["trace[2043680661] 'process raft request' (duration: 291.239836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.570646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.399217ms","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-25T10:05:15.57085Z","caller":"traceutil/trace.go:171","msg":"trace[1302667602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1094; }","duration":"187.645423ms","start":"2026-02-25T10:05:15.383193Z","end":"2026-02-25T10:05:15.570838Z","steps":["trace[1302667602] 'agreement among raft nodes before linearized reading' (duration: 187.331726ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.771236Z","caller":"traceutil/trace.go:171","msg":"trace[597820961] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"196.162611ms","start":"2026-02-25T10:05:15.575038Z","end":"2026-02-25T10:05:15.7712Z","steps":["trace[597820961] 'process raft request' (duration: 165.018189ms)","trace[597820961] 'compare' (duration: 30.854046ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:16.95037Z","caller":"traceutil/trace.go:171","msg":"trace[455147047] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"131.401252ms","start":"2026-02-25T10:05:16.818938Z","end":"2026-02-25T10:05:16.950339Z","steps":["trace[455147047] 'process raft request' (duration: 131.174307ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:17.698859Z","caller":"traceutil/trace.go:171","msg":"trace[477593956] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"122.135256ms","start":"2026-02-25T10:05:17.576677Z","end":"2026-02-25T10:05:17.698812Z","steps":["trace[477593956] 'process raft request' (duration: 121.960222ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:23.967194Z","caller":"traceutil/trace.go:171","msg":"trace[850530743] linearizableReadLoop","detail":"{readStateIndex:1187; appliedIndex:1186; }","duration":"211.80362ms","start":"2026-02-25T10:05:23.75537Z","end":"2026-02-25T10:05:23.967174Z","steps":["trace[850530743] 'read index received' (duration: 211.655917ms)","trace[850530743] 'applied index is now lower than readState.Index' (duration: 146.453µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:23.967288Z","caller":"traceutil/trace.go:171","msg":"trace[1486131061] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"240.646091ms","start":"2026-02-25T10:05:23.726618Z","end":"2026-02-25T10:05:23.967264Z","steps":["trace[1486131061] 'process raft request' (duration: 240.381926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:23.967555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.132507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T10:05:23.96765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.317585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-25T10:05:23.967675Z","caller":"traceutil/trace.go:171","msg":"trace[1416547064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1127; }","duration":"212.283431ms","start":"2026-02-25T10:05:23.755363Z","end":"2026-02-25T10:05:23.967646Z","steps":["trace[1416547064] 'agreement among raft nodes before linearized reading' (duration: 211.921713ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:23.96769Z","caller":"traceutil/trace.go:171","msg":"trace[1056297164] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1127; }","duration":"200.384306ms","start":"2026-02-25T10:05:23.767294Z","end":"2026-02-25T10:05:23.967679Z","steps":["trace[1056297164] 'agreement among raft nodes before linearized reading' (duration: 200.210963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:24.130442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.978745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:24.130899Z","caller":"traceutil/trace.go:171","msg":"trace[574028855] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"158.682078ms","start":"2026-02-25T10:05:23.972183Z","end":"2026-02-25T10:05:24.130865Z","steps":["trace[574028855] 'process raft request' (duration: 158.402382ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:24.130929Z","caller":"traceutil/trace.go:171","msg":"trace[416574280] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"158.74677ms","start":"2026-02-25T10:05:23.972136Z","end":"2026-02-25T10:05:24.130883Z","steps":["trace[416574280] 'process raft request' (duration: 57.246583ms)","trace[416574280] 'compare' (duration: 100.606407ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:41.817078Z","caller":"traceutil/trace.go:171","msg":"trace[713510323] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"124.984333ms","start":"2026-02-25T10:05:41.692066Z","end":"2026-02-25T10:05:41.81705Z","steps":["trace[713510323] 'process raft request' (duration: 124.88563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:42.134464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.257206ms","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-25T10:05:42.134565Z","caller":"traceutil/trace.go:171","msg":"trace[1147684336] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1202; }","duration":"137.410049ms","start":"2026-02-25T10:05:41.997132Z","end":"2026-02-25T10:05:42.134542Z","steps":["trace[1147684336] 'range keys from in-memory index tree' (duration: 137.02333ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:42.262646Z","caller":"traceutil/trace.go:171","msg":"trace[111761617] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"123.042199ms","start":"2026-02-25T10:05:42.139573Z","end":"2026-02-25T10:05:42.262615Z","steps":["trace[111761617] 'process raft request' (duration: 122.599288ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:42.537276Z","caller":"traceutil/trace.go:171","msg":"trace[2083705117] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1266; }","duration":"234.057272ms","start":"2026-02-25T10:05:42.303198Z","end":"2026-02-25T10:05:42.537255Z","steps":["trace[2083705117] 'read index received' (duration: 157.481316ms)","trace[2083705117] 'applied index is now lower than readState.Index' (duration: 76.574625ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:42.537528Z","caller":"traceutil/trace.go:171","msg":"trace[932429765] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"270.520333ms","start":"2026-02-25T10:05:42.266961Z","end":"2026-02-25T10:05:42.537481Z","steps":["trace[932429765] 'process raft request' (duration: 193.776455ms)","trace[932429765] 'compare' (duration: 76.280628ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:42.537579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.337307ms","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-25T10:05:42.537645Z","caller":"traceutil/trace.go:171","msg":"trace[1569838835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1204; }","duration":"234.46635ms","start":"2026-02-25T10:05:42.303146Z","end":"2026-02-25T10:05:42.537612Z","steps":["trace[1569838835] 'agreement among raft nodes before linearized reading' (duration: 234.236215ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:43.937896Z","caller":"traceutil/trace.go:171","msg":"trace[46567320] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"115.468121ms","start":"2026-02-25T10:05:43.822401Z","end":"2026-02-25T10:05:43.937869Z","steps":["trace[46567320] 'process raft request' (duration: 115.330178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.755704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.322304ms","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-25T10:05:44.755809Z","caller":"traceutil/trace.go:171","msg":"trace[30341511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1212; }","duration":"140.462568ms","start":"2026-02-25T10:05:44.615318Z","end":"2026-02-25T10:05:44.75578Z","steps":["trace[30341511] 'agreement among raft nodes before linearized reading' (duration: 28.645528ms)","trace[30341511] 'range keys from in-memory index tree' (duration: 111.521812ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:44.755864Z","caller":"traceutil/trace.go:171","msg":"trace[1225853822] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"108.010955ms","start":"2026-02-25T10:05:44.64783Z","end":"2026-02-25T10:05:44.755841Z","steps":["trace[1225853822] 'process raft request' (duration: 43.788995ms)","trace[1225853822] 'compare' (duration: 63.949253ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:44.756558Z","caller":"traceutil/trace.go:171","msg":"trace[2042963278] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"106.89291ms","start":"2026-02-25T10:05:44.649641Z","end":"2026-02-25T10:05:44.756534Z","steps":["trace[2042963278] 'process raft request' (duration: 106.134953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.975301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.414969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:05:44.975381Z","caller":"traceutil/trace.go:171","msg":"trace[1015325810] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1215; }","duration":"119.559762ms","start":"2026-02-25T10:05:44.855803Z","end":"2026-02-25T10:05:44.975362Z","steps":["trace[1015325810] 'count revisions from in-memory index tree' (duration: 119.289076ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:47.457091Z","caller":"traceutil/trace.go:171","msg":"trace[923513356] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"186.155215ms","start":"2026-02-25T10:05:47.270905Z","end":"2026-02-25T10:05:47.45706Z","steps":["trace[923513356] 'process raft request' (duration: 186.002722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:48.127193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.615171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:05:48.12722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.287446ms","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-25T10:05:48.127248Z","caller":"traceutil/trace.go:171","msg":"trace[1534648694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1224; }","duration":"511.719604ms","start":"2026-02-25T10:05:47.615516Z","end":"2026-02-25T10:05:48.127236Z","steps":["trace[1534648694] 'range keys from in-memory index tree' (duration: 511.494638ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:48.127259Z","caller":"traceutil/trace.go:171","msg":"trace[1844067315] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1224; }","duration":"384.356017ms","start":"2026-02-25T10:05:47.742892Z","end":"2026-02-25T10:05:48.127248Z","steps":["trace[1844067315] 'count revisions from in-memory index tree' (duration: 384.201264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:48.127271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:47.615492Z","time spent":"511.772314ms","remote":"127.0.0.1:40988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:05:48.127282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:47.742874Z","time spent":"384.402598ms","remote":"127.0.0.1:41194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:05:48.127302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.579365ms","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-25T10:05:48.127327Z","caller":"traceutil/trace.go:171","msg":"trace[878706476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1224; }","duration":"173.605025ms","start":"2026-02-25T10:05:47.953714Z","end":"2026-02-25T10:05:48.127319Z","steps":["trace[878706476] 'range keys from in-memory index tree' (duration: 173.323919ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:48.421393Z","caller":"traceutil/trace.go:171","msg":"trace[1047800448] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1289; }","duration":"108.524426ms","start":"2026-02-25T10:05:48.312845Z","end":"2026-02-25T10:05:48.421369Z","steps":["trace[1047800448] 'read index received' (duration: 16.387055ms)","trace[1047800448] 'applied index is now lower than readState.Index' (duration: 92.136191ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:48.421418Z","caller":"traceutil/trace.go:171","msg":"trace[1429360592] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"291.277685ms","start":"2026-02-25T10:05:48.130112Z","end":"2026-02-25T10:05:48.42139Z","steps":["trace[1429360592] 'process raft request' (duration: 198.940289ms)","trace[1429360592] 'compare' (duration: 92.09492ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:48.42144Z","caller":"traceutil/trace.go:171","msg":"trace[1414056346] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"208.545222ms","start":"2026-02-25T10:05:48.212871Z","end":"2026-02-25T10:05:48.421416Z","steps":["trace[1414056346] 'process raft request' (duration: 208.42317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:48.421534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.660469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:48.421634Z","caller":"traceutil/trace.go:171","msg":"trace[156462643] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1226; }","duration":"108.786102ms","start":"2026-02-25T10:05:48.312798Z","end":"2026-02-25T10:05:48.421584Z","steps":["trace[156462643] 'agreement among raft nodes before linearized reading' (duration: 108.662469ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:48.80173Z","caller":"traceutil/trace.go:171","msg":"trace[1040065596] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"184.737654ms","start":"2026-02-25T10:05:48.616966Z","end":"2026-02-25T10:05:48.801704Z","steps":["trace[1040065596] 'process raft request' (duration: 184.353135ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:48.937808Z","caller":"traceutil/trace.go:171","msg":"trace[1828503549] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"132.603972ms","start":"2026-02-25T10:05:48.805176Z","end":"2026-02-25T10:05:48.93778Z","steps":["trace[1828503549] 'process raft request' (duration: 100.973338ms)","trace[1828503549] 'compare' (duration: 31.144823ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:49.197049Z","caller":"traceutil/trace.go:171","msg":"trace[1806090794] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"177.757408ms","start":"2026-02-25T10:05:49.01926Z","end":"2026-02-25T10:05:49.197018Z","steps":["trace[1806090794] 'process raft request' (duration: 103.823242ms)","trace[1806090794] 'compare' (duration: 73.775563ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:52.928119Z","caller":"traceutil/trace.go:171","msg":"trace[1495535200] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"102.157716ms","start":"2026-02-25T10:05:52.825941Z","end":"2026-02-25T10:05:52.928099Z","steps":["trace[1495535200] 'process raft request' (duration: 102.054303ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:53.316942Z","caller":"traceutil/trace.go:171","msg":"trace[1049914638] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"105.98232ms","start":"2026-02-25T10:05:53.210914Z","end":"2026-02-25T10:05:53.316896Z","steps":["trace[1049914638] 'read index received' (duration: 34.849706ms)","trace[1049914638] 'applied index is now lower than readState.Index' (duration: 71.129804ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:53.317199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.268366ms","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-25T10:05:53.317292Z","caller":"traceutil/trace.go:171","msg":"trace[1157035024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1248; }","duration":"106.419579ms","start":"2026-02-25T10:05:53.210851Z","end":"2026-02-25T10:05:53.317271Z","steps":["trace[1157035024] 'agreement among raft nodes before linearized reading' (duration: 106.152393ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:53.516469Z","caller":"traceutil/trace.go:171","msg":"trace[1720803141] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"193.499098ms","start":"2026-02-25T10:05:53.32288Z","end":"2026-02-25T10:05:53.51638Z","steps":["trace[1720803141] 'process raft request' (duration: 192.789493ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:57.124743Z","caller":"traceutil/trace.go:171","msg":"trace[916053780] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"100.050028ms","start":"2026-02-25T10:05:57.024658Z","end":"2026-02-25T10:05:57.124708Z","steps":["trace[916053780] 'process raft request' (duration: 99.862194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:57.298573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.541171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:57.298699Z","caller":"traceutil/trace.go:171","msg":"trace[1962946916] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"168.647505ms","start":"2026-02-25T10:05:57.130031Z","end":"2026-02-25T10:05:57.298679Z","steps":["trace[1962946916] 'process raft request' (duration: 66.896369ms)","trace[1962946916] 'compare' (duration: 101.398468ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:06:43.396289Z","caller":"traceutil/trace.go:171","msg":"trace[1014257842] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"132.99826ms","start":"2026-02-25T10:06:43.263261Z","end":"2026-02-25T10:06:43.396259Z","steps":["trace[1014257842] 'process raft request' (duration: 132.816146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:47.708755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.044809ms","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-25T10:06:47.708936Z","caller":"traceutil/trace.go:171","msg":"trace[62584149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1465; }","duration":"105.316555ms","start":"2026-02-25T10:06:47.60359Z","end":"2026-02-25T10:06:47.708907Z","steps":["trace[62584149] 'range keys from in-memory index tree' (duration: 104.6416ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:47.901473Z","caller":"traceutil/trace.go:171","msg":"trace[250014010] linearizableReadLoop","detail":"{readStateIndex:1544; appliedIndex:1541; }","duration":"143.924084ms","start":"2026-02-25T10:06:47.757523Z","end":"2026-02-25T10:06:47.901447Z","steps":["trace[250014010] 'read index received' (duration: 3.985219ms)","trace[250014010] 'applied index is now lower than readState.Index' (duration: 139.937415ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:06:47.901505Z","caller":"traceutil/trace.go:171","msg":"trace[815899728] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"186.813768ms","start":"2026-02-25T10:06:47.714663Z","end":"2026-02-25T10:06:47.901477Z","steps":["trace[815899728] 'process raft request' (duration: 186.730396ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:47.901524Z","caller":"traceutil/trace.go:171","msg":"trace[2008723549] transaction","detail":"{read_only:false; response_revision:1467; number_of_response:1; }","duration":"187.055264ms","start":"2026-02-25T10:06:47.714416Z","end":"2026-02-25T10:06:47.901471Z","steps":["trace[2008723549] 'process raft request' (duration: 151.38187ms)","trace[2008723549] 'compare' (duration: 35.443868ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:47.901728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.1905ms","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-25T10:06:47.901777Z","caller":"traceutil/trace.go:171","msg":"trace[915552689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1468; }","duration":"144.285292ms","start":"2026-02-25T10:06:47.757478Z","end":"2026-02-25T10:06:47.901764Z","steps":["trace[915552689] 'agreement among raft nodes before linearized reading' (duration: 144.068417ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:50.613754Z","caller":"traceutil/trace.go:171","msg":"trace[1765673407] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"101.552271ms","start":"2026-02-25T10:06:50.512176Z","end":"2026-02-25T10:06:50.613728Z","steps":["trace[1765673407] 'process raft request' (duration: 101.370397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:51.375585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.340395ms","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-25T10:06:51.375613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.506621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-25T10:06:51.375665Z","caller":"traceutil/trace.go:171","msg":"trace[758254161] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1481; }","duration":"145.476248ms","start":"2026-02-25T10:06:51.230168Z","end":"2026-02-25T10:06:51.375644Z","steps":["trace[758254161] 'range keys from in-memory index tree' (duration: 145.10677ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:51.375713Z","caller":"traceutil/trace.go:171","msg":"trace[37354262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1481; }","duration":"155.674195ms","start":"2026-02-25T10:06:51.220014Z","end":"2026-02-25T10:06:51.375688Z","steps":["trace[37354262] 'range keys from in-memory index tree' (duration: 155.273886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:51.800067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.614592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:06:51.800474Z","caller":"traceutil/trace.go:171","msg":"trace[806341291] linearizableReadLoop","detail":"{readStateIndex:1560; appliedIndex:1559; }","duration":"324.410311ms","start":"2026-02-25T10:06:51.475856Z","end":"2026-02-25T10:06:51.800266Z","steps":["trace[806341291] 'read index received' (duration: 4.236384ms)","trace[806341291] 'applied index is now lower than readState.Index' (duration: 320.084575ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:06:51.800483Z","caller":"traceutil/trace.go:171","msg":"trace[75358521] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"420.564761ms","start":"2026-02-25T10:06:51.379878Z","end":"2026-02-25T10:06:51.800443Z","steps":["trace[75358521] 'process raft request' (duration: 100.17787ms)","trace[75358521] 'compare' (duration: 318.390207ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:51.800613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:51.379859Z","time spent":"420.685074ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:06:51.80117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.788436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:06:51.801229Z","caller":"traceutil/trace.go:171","msg":"trace[1214343896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1483; }","duration":"185.880198ms","start":"2026-02-25T10:06:51.615337Z","end":"2026-02-25T10:06:51.801217Z","steps":["trace[1214343896] 'agreement among raft nodes before linearized reading' (duration: 185.796556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:51.801481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.990195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:06:51.801625Z","caller":"traceutil/trace.go:171","msg":"trace[1558863078] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1483; }","duration":"144.145159ms","start":"2026-02-25T10:06:51.657427Z","end":"2026-02-25T10:06:51.801572Z","steps":["trace[1558863078] 'agreement among raft nodes before linearized reading' (duration: 143.927955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:51.80207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.391104ms","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-25T10:06:51.802173Z","caller":"traceutil/trace.go:171","msg":"trace[1706593342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1483; }","duration":"326.335294ms","start":"2026-02-25T10:06:51.475815Z","end":"2026-02-25T10:06:51.80215Z","steps":["trace[1706593342] 'agreement among raft nodes before linearized reading' (duration: 325.170348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:51.802259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:51.475801Z","time spent":"326.419817ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T10:06:52.029061Z","caller":"traceutil/trace.go:171","msg":"trace[1884589782] linearizableReadLoop","detail":"{readStateIndex:1562; appliedIndex:1561; }","duration":"108.862783ms","start":"2026-02-25T10:06:51.920157Z","end":"2026-02-25T10:06:52.02902Z","steps":["trace[1884589782] 'read index received' (duration: 108.554676ms)","trace[1884589782] 'applied index is now lower than readState.Index' (duration: 306.687µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:06:52.029116Z","caller":"traceutil/trace.go:171","msg":"trace[129615418] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"174.555915ms","start":"2026-02-25T10:06:51.854529Z","end":"2026-02-25T10:06:52.029085Z","steps":["trace[129615418] 'process raft request' (duration: 174.278069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:52.029367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.18739ms","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-25T10:06:52.029451Z","caller":"traceutil/trace.go:171","msg":"trace[807161437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1485; }","duration":"109.316353ms","start":"2026-02-25T10:06:51.920116Z","end":"2026-02-25T10:06:52.029432Z","steps":["trace[807161437] 'agreement among raft nodes before linearized reading' (duration: 109.053527ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:52.170035Z","caller":"traceutil/trace.go:171","msg":"trace[1433605539] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"136.816135ms","start":"2026-02-25T10:06:52.033166Z","end":"2026-02-25T10:06:52.169983Z","steps":["trace[1433605539] 'process raft request' (duration: 56.703312ms)","trace[1433605539] 'compare' (duration: 79.940279ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:06:52.170218Z","caller":"traceutil/trace.go:171","msg":"trace[1751787135] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"137.02195ms","start":"2026-02-25T10:06:52.033166Z","end":"2026-02-25T10:06:52.170188Z","steps":["trace[1751787135] 'process raft request' (duration: 136.791615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:52.898827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.680407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:06:52.898933Z","caller":"traceutil/trace.go:171","msg":"trace[1332765173] linearizableReadLoop","detail":"{readStateIndex:1565; appliedIndex:1564; }","duration":"255.360914ms","start":"2026-02-25T10:06:52.643556Z","end":"2026-02-25T10:06:52.898916Z","steps":["trace[1332765173] 'read index received' (duration: 33.361µs)","trace[1332765173] 'applied index is now lower than readState.Index' (duration: 255.326223ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:52.899008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.446686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:06:52.899039Z","caller":"traceutil/trace.go:171","msg":"trace[936946678] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1488; }","duration":"255.521107ms","start":"2026-02-25T10:06:52.64351Z","end":"2026-02-25T10:06:52.899031Z","steps":["trace[936946678] 'agreement among raft nodes before linearized reading' (duration: 255.450576ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:52.899038Z","caller":"traceutil/trace.go:171","msg":"trace[1268428817] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"676.208521ms","start":"2026-02-25T10:06:52.222806Z","end":"2026-02-25T10:06:52.899015Z","steps":["trace[1268428817] 'process raft request' (duration: 236.206587ms)","trace[1268428817] 'compare' (duration: 439.425921ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:52.899126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:52.222788Z","time spent":"676.295374ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:06:53.093929Z","caller":"traceutil/trace.go:171","msg":"trace[1174496466] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"182.802088ms","start":"2026-02-25T10:06:52.911101Z","end":"2026-02-25T10:06:53.093903Z","steps":["trace[1174496466] 'process raft request' (duration: 118.863575ms)","trace[1174496466] 'compare' (duration: 63.828981ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:06.399082Z","caller":"traceutil/trace.go:171","msg":"trace[80403031] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"123.13132ms","start":"2026-02-25T10:07:06.275923Z","end":"2026-02-25T10:07:06.399055Z","steps":["trace[80403031] 'process raft request' (duration: 122.965437ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:06.445639Z","caller":"traceutil/trace.go:171","msg":"trace[25792756] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"169.307518ms","start":"2026-02-25T10:07:06.276305Z","end":"2026-02-25T10:07:06.445612Z","steps":["trace[25792756] 'process raft request' (duration: 169.171045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:23.000265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.854754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:08:23.000375Z","caller":"traceutil/trace.go:171","msg":"trace[82423092] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1881; }","duration":"384.006077ms","start":"2026-02-25T10:08:22.616348Z","end":"2026-02-25T10:08:23.000354Z","steps":["trace[82423092] 'range keys from in-memory index tree' (duration: 383.783452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:23.000418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:22.616335Z","time spent":"384.073488ms","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:08:23.000403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.487496ms","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-25T10:08:23.000533Z","caller":"traceutil/trace.go:171","msg":"trace[2128487039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1881; }","duration":"153.67969ms","start":"2026-02-25T10:08:22.846822Z","end":"2026-02-25T10:08:23.000501Z","steps":["trace[2128487039] 'range keys from in-memory index tree' (duration: 153.18716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:23.000602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.356805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-25T10:08:23.000708Z","caller":"traceutil/trace.go:171","msg":"trace[1029080102] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1881; }","duration":"406.513858ms","start":"2026-02-25T10:08:22.594168Z","end":"2026-02-25T10:08:23.000681Z","steps":["trace[1029080102] 'range keys from in-memory index tree' (duration: 405.942186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:23.00076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:22.594155Z","time spent":"406.59446ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-25T10:08:23.000985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.326479ms","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-25T10:08:23.001022Z","caller":"traceutil/trace.go:171","msg":"trace[1592524675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1881; }","duration":"169.406291ms","start":"2026-02-25T10:08:22.831606Z","end":"2026-02-25T10:08:23.001012Z","steps":["trace[1592524675] 'range keys from in-memory index tree' (duration: 168.665384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:23.000981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.112108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-25T10:08:23.001243Z","caller":"traceutil/trace.go:171","msg":"trace[1585612035] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1881; }","duration":"410.378084ms","start":"2026-02-25T10:08:22.590796Z","end":"2026-02-25T10:08:23.001174Z","steps":["trace[1585612035] 'range keys from in-memory index tree' (duration: 409.25537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:23.001311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:22.590775Z","time spent":"410.518217ms","remote":"127.0.0.1:41256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}