{"level":"warn","ts":"2026-02-25T19:31:15.948465Z","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-25T19:31:15.948598Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.67:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.67:2380","--initial-cluster=instance=https://199.204.45.67:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.67:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.67: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-25T19:31:15.948675Z","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-25T19:31:15.948703Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.67:2380"]} {"level":"info","ts":"2026-02-25T19:31:15.948729Z","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-25T19:31:15.949341Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.67:2379"]} {"level":"info","ts":"2026-02-25T19:31:15.94961Z","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://199.204.45.67:2380"],"listen-peer-urls":["https://199.204.45.67:2380"],"advertise-client-urls":["https://199.204.45.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.67: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-25T19:31:15.954298Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.461097ms"} {"level":"info","ts":"2026-02-25T19:31:15.967967Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cddd08233fdda28a","cluster-id":"72e3bf4964adc703"} {"level":"info","ts":"2026-02-25T19:31:15.968055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T19:31:15.968084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became follower at term 0"} {"level":"info","ts":"2026-02-25T19:31:15.968097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cddd08233fdda28a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T19:31:15.968114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became follower at term 1"} {"level":"info","ts":"2026-02-25T19:31:15.968186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a switched to configuration voters=(14834021695116649098)"} {"level":"warn","ts":"2026-02-25T19:31:15.977508Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T19:31:15.991843Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T19:31:15.994117Z","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-25T19:31:15.996673Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cddd08233fdda28a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T19:31:15.996773Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cddd08233fdda28a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T19:31:15.99701Z","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-25T19:31:15.997043Z","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-25T19:31:15.997051Z","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-25T19:31:15.999299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a switched to configuration voters=(14834021695116649098)"} {"level":"info","ts":"2026-02-25T19:31:15.999419Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"72e3bf4964adc703","local-member-id":"cddd08233fdda28a","added-peer-id":"cddd08233fdda28a","added-peer-peer-urls":["https://199.204.45.67:2380"]} {"level":"info","ts":"2026-02-25T19:31:15.999496Z","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-25T19:31:15.999745Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cddd08233fdda28a","initial-advertise-peer-urls":["https://199.204.45.67:2380"],"listen-peer-urls":["https://199.204.45.67:2380"],"advertise-client-urls":["https://199.204.45.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T19:31:15.999791Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T19:31:16.000328Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.67:2380"} {"level":"info","ts":"2026-02-25T19:31:16.000364Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.67:2380"} {"level":"info","ts":"2026-02-25T19:31:16.668899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T19:31:16.668984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T19:31:16.669011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a received MsgPreVoteResp from cddd08233fdda28a at term 1"} {"level":"info","ts":"2026-02-25T19:31:16.669032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became candidate at term 2"} {"level":"info","ts":"2026-02-25T19:31:16.669043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a received MsgVoteResp from cddd08233fdda28a at term 2"} {"level":"info","ts":"2026-02-25T19:31:16.669058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became leader at term 2"} {"level":"info","ts":"2026-02-25T19:31:16.669072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cddd08233fdda28a elected leader cddd08233fdda28a at term 2"} {"level":"info","ts":"2026-02-25T19:31:16.670076Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cddd08233fdda28a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.67:2379]}","request-path":"/0/members/cddd08233fdda28a/attributes","cluster-id":"72e3bf4964adc703","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T19:31:16.670114Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:31:16.670396Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:31:16.670994Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:31:16.674076Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.67:2379"} {"level":"info","ts":"2026-02-25T19:31:16.674461Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T19:31:16.680268Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"72e3bf4964adc703","local-member-id":"cddd08233fdda28a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:31:16.680394Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:31:16.680431Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:31:16.68064Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T19:31:16.680687Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T19:31:18.370262Z","caller":"traceutil/trace.go:171","msg":"trace[158301312] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"376.133961ms","start":"2026-02-25T19:31:17.994101Z","end":"2026-02-25T19:31:18.370235Z","steps":["trace[158301312] 'read index received' (duration: 375.942607ms)","trace[158301312] 'applied index is now lower than readState.Index' (duration: 187.474µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:18.370308Z","caller":"traceutil/trace.go:171","msg":"trace[33193087] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"376.746165ms","start":"2026-02-25T19:31:17.993521Z","end":"2026-02-25T19:31:18.370267Z","steps":["trace[33193087] 'process raft request' (duration: 376.451398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:18.370545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.419367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:31:18.370604Z","caller":"traceutil/trace.go:171","msg":"trace[223750524] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:2; }","duration":"376.515919ms","start":"2026-02-25T19:31:17.994078Z","end":"2026-02-25T19:31:18.370594Z","steps":["trace[223750524] 'agreement among raft nodes before linearized reading' (duration: 376.302294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:18.37064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:17.994066Z","time spent":"376.565239ms","remote":"127.0.0.1:39426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":27,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-25T19:31:18.371466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:17.993498Z","time spent":"376.887668ms","remote":"127.0.0.1:39324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:18.871134Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345950209933443,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:31:19.371977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345950209933443,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:31:19.872903Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345950209933443,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:31:19.922518Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.331269082s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:31:19.922959Z","caller":"traceutil/trace.go:171","msg":"trace[1918754441] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.928026032s","start":"2026-02-25T19:31:17.994873Z","end":"2026-02-25T19:31:19.922899Z","steps":["trace[1918754441] 'process raft request' (duration: 1.927859218s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:19.923153Z","caller":"traceutil/trace.go:171","msg":"trace[614299096] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:5; }","duration":"1.552777331s","start":"2026-02-25T19:31:18.370355Z","end":"2026-02-25T19:31:19.923132Z","steps":["trace[614299096] 'read index received' (duration: 1.552321611s)","trace[614299096] 'applied index is now lower than readState.Index' (duration: 454.67µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:19.923159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:17.994862Z","time spent":"1.928209806s","remote":"127.0.0.1:39314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:19.923276Z","caller":"traceutil/trace.go:171","msg":"trace[679006229] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.877874922s","start":"2026-02-25T19:31:18.045351Z","end":"2026-02-25T19:31:19.923226Z","steps":["trace[679006229] 'process raft request' (duration: 1.877706518s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:19.923304Z","caller":"traceutil/trace.go:171","msg":"trace[1934270709] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.877401812s","start":"2026-02-25T19:31:18.045854Z","end":"2026-02-25T19:31:19.923256Z","steps":["trace[1934270709] 'process raft request' (duration: 1.877240228s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:19.923346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.870243594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T19:31:19.923381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.891489061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T19:31:19.923418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:18.045835Z","time spent":"1.877531644s","remote":"127.0.0.1:39510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:31:19.923439Z","caller":"traceutil/trace.go:171","msg":"trace[623236683] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"1.891578682s","start":"2026-02-25T19:31:18.031841Z","end":"2026-02-25T19:31:19.92342Z","steps":["trace[623236683] 'agreement among raft nodes before linearized reading' (duration: 1.891400698s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:19.923437Z","caller":"traceutil/trace.go:171","msg":"trace[961810244] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.870359227s","start":"2026-02-25T19:31:18.053053Z","end":"2026-02-25T19:31:19.923413Z","steps":["trace[961810244] 'agreement among raft nodes before linearized reading' (duration: 1.870165573s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:19.923488Z","caller":"traceutil/trace.go:171","msg":"trace[744479746] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.893264039s","start":"2026-02-25T19:31:18.030214Z","end":"2026-02-25T19:31:19.923478Z","steps":["trace[744479746] 'process raft request' (duration: 1.892635676s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:19.923436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:18.045341Z","time spent":"1.878015465s","remote":"127.0.0.1:39764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:31:19.923545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:18.030189Z","time spent":"1.893332671s","remote":"127.0.0.1:39684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:31:19.923579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.917280576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T19:31:19.923612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.538873915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T19:31:19.923663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.895524538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.67\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:31:19.923704Z","caller":"traceutil/trace.go:171","msg":"trace[1122663111] range","detail":"{range_begin:/registry/masterleases/199.204.45.67; range_end:; response_count:0; response_revision:11; }","duration":"1.895565399s","start":"2026-02-25T19:31:18.02813Z","end":"2026-02-25T19:31:19.923696Z","steps":["trace[1122663111] 'agreement among raft nodes before linearized reading' (duration: 1.895516158s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:19.923722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.840548624s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T19:31:19.923733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:18.028068Z","time spent":"1.895658402s","remote":"127.0.0.1:39294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.67\" "} {"level":"warn","ts":"2026-02-25T19:31:19.923734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.707785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T19:31:19.923795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.870266114s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:31:19.923833Z","caller":"traceutil/trace.go:171","msg":"trace[2133256912] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.870330186s","start":"2026-02-25T19:31:18.053495Z","end":"2026-02-25T19:31:19.923825Z","steps":["trace[2133256912] 'agreement among raft nodes before linearized reading' (duration: 1.870284545s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:19.923626Z","caller":"traceutil/trace.go:171","msg":"trace[2055590930] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.917342127s","start":"2026-02-25T19:31:18.006267Z","end":"2026-02-25T19:31:19.923609Z","steps":["trace[2055590930] 'agreement among raft nodes before linearized reading' (duration: 1.917279276s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:19.923874Z","caller":"traceutil/trace.go:171","msg":"trace[1472561602] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.880115471s","start":"2026-02-25T19:31:18.043747Z","end":"2026-02-25T19:31:19.923862Z","steps":["trace[1472561602] 'process raft request' (duration: 1.87913592s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:19.923905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:18.006251Z","time spent":"1.917645273s","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-25T19:31:19.923927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:18.04373Z","time spent":"1.880174053s","remote":"127.0.0.1:39764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:31:19.923952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.179569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:31:19.923761Z","caller":"traceutil/trace.go:171","msg":"trace[40249758] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.840601644s","start":"2026-02-25T19:31:18.083143Z","end":"2026-02-25T19:31:19.923744Z","steps":["trace[40249758] 'agreement among raft nodes before linearized reading' (duration: 1.840553933s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:19.924Z","caller":"traceutil/trace.go:171","msg":"trace[1632190477] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"995.21827ms","start":"2026-02-25T19:31:18.928762Z","end":"2026-02-25T19:31:19.92398Z","steps":["trace[1632190477] 'agreement among raft nodes before linearized reading' (duration: 995.164599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:19.92349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:18.031829Z","time spent":"1.891644164s","remote":"127.0.0.1:39334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-02-25T19:31:19.92403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:18.083129Z","time spent":"1.840886501s","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-25T19:31:19.924043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:18.928713Z","time spent":"995.317822ms","remote":"127.0.0.1:39594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-02-25T19:31:19.924069Z","caller":"traceutil/trace.go:171","msg":"trace[650015946] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.879108168s","start":"2026-02-25T19:31:18.044949Z","end":"2026-02-25T19:31:19.924057Z","steps":["trace[650015946] 'process raft request' (duration: 1.877986654s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:19.923544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.89420171s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:31:19.924161Z","caller":"traceutil/trace.go:171","msg":"trace[1034134907] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.894815943s","start":"2026-02-25T19:31:18.029333Z","end":"2026-02-25T19:31:19.924148Z","steps":["trace[1034134907] 'agreement among raft nodes before linearized reading' (duration: 1.894182849s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:19.924199Z","caller":"traceutil/trace.go:171","msg":"trace[705421375] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.878921856s","start":"2026-02-25T19:31:18.045266Z","end":"2026-02-25T19:31:19.924188Z","steps":["trace[705421375] 'process raft request' (duration: 1.877697778s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:19.92421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:18.029325Z","time spent":"1.894868806s","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-25T19:31:19.924005Z","caller":"traceutil/trace.go:171","msg":"trace[1101455684] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.879532238s","start":"2026-02-25T19:31:18.044465Z","end":"2026-02-25T19:31:19.923997Z","steps":["trace[1101455684] 'process raft request' (duration: 1.878446144s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:19.923668Z","caller":"traceutil/trace.go:171","msg":"trace[244155707] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"1.538950017s","start":"2026-02-25T19:31:18.384705Z","end":"2026-02-25T19:31:19.923655Z","steps":["trace[244155707] 'agreement among raft nodes before linearized reading' (duration: 1.538883466s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:19.924287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:18.384683Z","time spent":"1.539596972s","remote":"127.0.0.1:39648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-25T19:31:19.924309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:18.044442Z","time spent":"1.879814704s","remote":"127.0.0.1:39764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:31:19.924154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:18.044935Z","time spent":"1.87916798s","remote":"127.0.0.1:39764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:31:19.923905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.74758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:31:19.924574Z","caller":"traceutil/trace.go:171","msg":"trace[2073135409] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"995.409435ms","start":"2026-02-25T19:31:18.929149Z","end":"2026-02-25T19:31:19.924558Z","steps":["trace[2073135409] 'agreement among raft nodes before linearized reading' (duration: 994.729329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:19.924647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:18.92911Z","time spent":"995.523087ms","remote":"127.0.0.1:39618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-02-25T19:31:19.924021Z","caller":"traceutil/trace.go:171","msg":"trace[655058053] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.878684859s","start":"2026-02-25T19:31:18.045319Z","end":"2026-02-25T19:31:19.924004Z","steps":["trace[655058053] 'process raft request' (duration: 1.877668667s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:19.924767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:18.045301Z","time spent":"1.879426526s","remote":"127.0.0.1:39426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:31:19.923797Z","caller":"traceutil/trace.go:171","msg":"trace[966377671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"496.794067ms","start":"2026-02-25T19:31:19.42699Z","end":"2026-02-25T19:31:19.923784Z","steps":["trace[966377671] 'agreement among raft nodes before linearized reading' (duration: 496.711925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:19.924975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:19.426959Z","time spent":"498.002633ms","remote":"127.0.0.1:39256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:31:19.923859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:18.053482Z","time spent":"1.870371207s","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-25T19:31:19.923494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:18.053042Z","time spent":"1.870441919s","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-25T19:31:19.923856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.532122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:31:19.925697Z","caller":"traceutil/trace.go:171","msg":"trace[808325750] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"530.426045ms","start":"2026-02-25T19:31:19.395248Z","end":"2026-02-25T19:31:19.925674Z","steps":["trace[808325750] 'agreement among raft nodes before linearized reading' (duration: 528.575604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:19.925876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:19.395229Z","time spent":"530.619929ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-02-25T19:31:19.924375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:18.045254Z","time spent":"1.878976137s","remote":"127.0.0.1:39764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:31:21.645437Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.431293806s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T19:31:21.716866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.502366395s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T19:31:21.717597Z","caller":"traceutil/trace.go:171","msg":"trace[2002670201] linearizableReadLoop","detail":"{readStateIndex:16; appliedIndex:14; }","duration":"1.783995143s","start":"2026-02-25T19:31:19.933583Z","end":"2026-02-25T19:31:21.717578Z","steps":["trace[2002670201] 'read index received' (duration: 280.682997ms)","trace[2002670201] 'applied index is now lower than readState.Index' (duration: 1.503311286s)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:21.717713Z","caller":"traceutil/trace.go:171","msg":"trace[2050017721] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"1.782780647s","start":"2026-02-25T19:31:19.934919Z","end":"2026-02-25T19:31:21.717699Z","steps":["trace[2050017721] 'process raft request' (duration: 1.78247303s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:21.717689Z","caller":"traceutil/trace.go:171","msg":"trace[1585040825] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"1.782785357s","start":"2026-02-25T19:31:19.934871Z","end":"2026-02-25T19:31:21.717657Z","steps":["trace[1585040825] 'process raft request' (duration: 1.78245675s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:21.717848Z","caller":"traceutil/trace.go:171","msg":"trace[176714023] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"1.778951023s","start":"2026-02-25T19:31:19.938836Z","end":"2026-02-25T19:31:21.717787Z","steps":["trace[176714023] 'process raft request' (duration: 1.778660816s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:21.717872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:19.934864Z","time spent":"1.782909629s","remote":"127.0.0.1:39764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:31:21.717964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:19.93882Z","time spent":"1.779083305s","remote":"127.0.0.1:39426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3478,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:31:21.718011Z","caller":"traceutil/trace.go:171","msg":"trace[369017080] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"1.783025392s","start":"2026-02-25T19:31:19.934961Z","end":"2026-02-25T19:31:21.717986Z","steps":["trace[369017080] 'process raft request' (duration: 1.78246739s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:21.718053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.784449193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-crlhx\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-02-25T19:31:21.718114Z","caller":"traceutil/trace.go:171","msg":"trace[1649803205] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-crlhx; range_end:; response_count:1; response_revision:21; }","duration":"1.784542405s","start":"2026-02-25T19:31:19.933555Z","end":"2026-02-25T19:31:21.718097Z","steps":["trace[1649803205] 'agreement among raft nodes before linearized reading' (duration: 1.784327001s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:21.718134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:19.934956Z","time spent":"1.783123034s","remote":"127.0.0.1:39764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:31:21.71821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:19.933543Z","time spent":"1.784652847s","remote":"127.0.0.1:39510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-crlhx\" "} {"level":"info","ts":"2026-02-25T19:31:21.718235Z","caller":"traceutil/trace.go:171","msg":"trace[833300170] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"1.784936874s","start":"2026-02-25T19:31:19.933278Z","end":"2026-02-25T19:31:21.718215Z","steps":["trace[833300170] 'process raft request' (duration: 280.978603ms)","trace[833300170] 'compare' (duration: 1.502243543s)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:21.718246Z","caller":"traceutil/trace.go:171","msg":"trace[999311230] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"1.779370081s","start":"2026-02-25T19:31:19.938858Z","end":"2026-02-25T19:31:21.718228Z","steps":["trace[999311230] 'process raft request' (duration: 1.778600554s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:21.718308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:19.933268Z","time spent":"1.785004865s","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:31:21.718332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:19.938855Z","time spent":"1.779430892s","remote":"127.0.0.1:39648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:31:21.718421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.779027954s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:31:21.718522Z","caller":"traceutil/trace.go:171","msg":"trace[1878330978] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"1.77933484s","start":"2026-02-25T19:31:19.939176Z","end":"2026-02-25T19:31:21.71851Z","steps":["trace[1878330978] 'process raft request' (duration: 1.778370049s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:21.718547Z","caller":"traceutil/trace.go:171","msg":"trace[402407613] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:21; }","duration":"1.779455303s","start":"2026-02-25T19:31:19.939077Z","end":"2026-02-25T19:31:21.718533Z","steps":["trace[402407613] 'agreement among raft nodes before linearized reading' (duration: 1.779026063s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:21.718626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:19.939157Z","time spent":"1.779393122s","remote":"127.0.0.1:39618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:31:21.718666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:19.939072Z","time spent":"1.779577966s","remote":"127.0.0.1:39594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-02-25T19:31:21.717924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:19.934914Z","time spent":"1.782838168s","remote":"127.0.0.1:39764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:31:21.71885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29103436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:31:21.718868Z","caller":"traceutil/trace.go:171","msg":"trace[49448192] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"1.785381393s","start":"2026-02-25T19:31:19.933449Z","end":"2026-02-25T19:31:21.71883Z","steps":["trace[49448192] 'process raft request' (duration: 1.783541892s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:21.718903Z","caller":"traceutil/trace.go:171","msg":"trace[1269135803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21; }","duration":"1.291117631s","start":"2026-02-25T19:31:20.427769Z","end":"2026-02-25T19:31:21.718887Z","steps":["trace[1269135803] 'agreement among raft nodes before linearized reading' (duration: 1.29104055s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:21.718945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:19.933443Z","time spent":"1.785466155s","remote":"127.0.0.1:39684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:31:21.718957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:20.42775Z","time spent":"1.291196304s","remote":"127.0.0.1:39256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:31:21.719134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.581097461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:31:21.71919Z","caller":"traceutil/trace.go:171","msg":"trace[972382133] transaction","detail":"{read_only:false; number_of_response:0; response_revision:14; }","duration":"1.785522156s","start":"2026-02-25T19:31:19.933657Z","end":"2026-02-25T19:31:21.71918Z","steps":["trace[972382133] 'process raft request' (duration: 1.783602054s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:21.719227Z","caller":"traceutil/trace.go:171","msg":"trace[260940041] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:21; }","duration":"1.581234064s","start":"2026-02-25T19:31:20.137975Z","end":"2026-02-25T19:31:21.719209Z","steps":["trace[260940041] 'agreement among raft nodes before linearized reading' (duration: 1.581080791s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:21.719283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:19.933646Z","time spent":"1.785592008s","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:31:21.719312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.7328155s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:31:21.719345Z","caller":"traceutil/trace.go:171","msg":"trace[201325359] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:21; }","duration":"1.732944273s","start":"2026-02-25T19:31:19.98639Z","end":"2026-02-25T19:31:21.719334Z","steps":["trace[201325359] 'agreement among raft nodes before linearized reading' (duration: 1.732890432s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:21.719312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:20.137946Z","time spent":"1.581354027s","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-02-25T19:31:21.719374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:19.986371Z","time spent":"1.732996624s","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-02-25T19:31:21.719392Z","caller":"traceutil/trace.go:171","msg":"trace[1809177346] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"1.785178169s","start":"2026-02-25T19:31:19.934199Z","end":"2026-02-25T19:31:21.719377Z","steps":["trace[1809177346] 'process raft request' (duration: 1.783079384s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:21.719469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:19.934192Z","time spent":"1.78523426s","remote":"127.0.0.1:39764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:31:21.719522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.720060031s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-25T19:31:21.719549Z","caller":"traceutil/trace.go:171","msg":"trace[308226414] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"1.720119511s","start":"2026-02-25T19:31:19.999421Z","end":"2026-02-25T19:31:21.719541Z","steps":["trace[308226414] 'agreement among raft nodes before linearized reading' (duration: 1.72006883s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:21.719576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:19.999403Z","time spent":"1.720166303s","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-25T19:31:21.719142Z","caller":"traceutil/trace.go:171","msg":"trace[28844384] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"1.785504525s","start":"2026-02-25T19:31:19.93362Z","end":"2026-02-25T19:31:21.719124Z","steps":["trace[28844384] 'process raft request' (duration: 1.783598594s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:21.719674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:19.933615Z","time spent":"1.786023787s","remote":"127.0.0.1:39764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:31:21.719155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.909992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:31:21.719812Z","caller":"traceutil/trace.go:171","msg":"trace[1704684164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21; }","duration":"782.609747ms","start":"2026-02-25T19:31:20.937191Z","end":"2026-02-25T19:31:21.7198Z","steps":["trace[1704684164] 'agreement among raft nodes before linearized reading' (duration: 781.932412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:21.719846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:20.937095Z","time spent":"782.741851ms","remote":"127.0.0.1:39268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:31:51.150702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.166843ms","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-25T19:31:51.150806Z","caller":"traceutil/trace.go:171","msg":"trace[236740251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:515; }","duration":"775.312276ms","start":"2026-02-25T19:31:50.375463Z","end":"2026-02-25T19:31:51.150775Z","steps":["trace[236740251] 'range keys from in-memory index tree' (duration: 775.02026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:51.150848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:50.375439Z","time spent":"775.400419ms","remote":"127.0.0.1:39520","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-25T19:31:54.312781Z","caller":"traceutil/trace.go:171","msg":"trace[472698043] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"116.975926ms","start":"2026-02-25T19:31:54.195783Z","end":"2026-02-25T19:31:54.312759Z","steps":["trace[472698043] 'process raft request' (duration: 116.627638ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:55.326061Z","caller":"traceutil/trace.go:171","msg":"trace[1846028975] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"152.776461ms","start":"2026-02-25T19:31:55.173253Z","end":"2026-02-25T19:31:55.32603Z","steps":["trace[1846028975] 'read index received' (duration: 152.535046ms)","trace[1846028975] 'applied index is now lower than readState.Index' (duration: 240.275µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:31:55.326373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.103218ms","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-25T19:31:55.326535Z","caller":"traceutil/trace.go:171","msg":"trace[2038264622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:527; }","duration":"153.326334ms","start":"2026-02-25T19:31:55.173179Z","end":"2026-02-25T19:31:55.326505Z","steps":["trace[2038264622] 'agreement among raft nodes before linearized reading' (duration: 153.079928ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:55.326553Z","caller":"traceutil/trace.go:171","msg":"trace[134711861] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"185.171802ms","start":"2026-02-25T19:31:55.141353Z","end":"2026-02-25T19:31:55.326525Z","steps":["trace[134711861] 'process raft request' (duration: 184.516948ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:58.725068Z","caller":"traceutil/trace.go:171","msg":"trace[1192023516] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"132.17481ms","start":"2026-02-25T19:31:58.592861Z","end":"2026-02-25T19:31:58.725036Z","steps":["trace[1192023516] 'read index received' (duration: 131.934405ms)","trace[1192023516] 'applied index is now lower than readState.Index' (duration: 239.235µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:31:58.725192Z","caller":"traceutil/trace.go:171","msg":"trace[1481374482] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"383.735818ms","start":"2026-02-25T19:31:58.341396Z","end":"2026-02-25T19:31:58.725132Z","steps":["trace[1481374482] 'process raft request' (duration: 383.471672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:31:58.725437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:58.34136Z","time spent":"383.950802ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:31:58.731973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.078472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xzfzh\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-02-25T19:31:58.732071Z","caller":"traceutil/trace.go:171","msg":"trace[737113227] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xzfzh; range_end:; response_count:1; response_revision:538; }","duration":"139.271185ms","start":"2026-02-25T19:31:58.59278Z","end":"2026-02-25T19:31:58.732051Z","steps":["trace[737113227] 'agreement among raft nodes before linearized reading' (duration: 132.473916ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:31:58.980239Z","caller":"traceutil/trace.go:171","msg":"trace[1511069453] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"206.802176ms","start":"2026-02-25T19:31:58.773412Z","end":"2026-02-25T19:31:58.980214Z","steps":["trace[1511069453] 'process raft request' (duration: 206.638353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:00.666143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.682716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T19:32:00.666208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088408375s","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-25T19:32:00.666276Z","caller":"traceutil/trace.go:171","msg":"trace[1621719704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"1.088539027s","start":"2026-02-25T19:31:59.577719Z","end":"2026-02-25T19:32:00.666258Z","steps":["trace[1621719704] 'range keys from in-memory index tree' (duration: 1.08819595s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:00.666275Z","caller":"traceutil/trace.go:171","msg":"trace[1433097598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:539; }","duration":"734.85479ms","start":"2026-02-25T19:31:59.931387Z","end":"2026-02-25T19:32:00.666242Z","steps":["trace[1433097598] 'range keys from in-memory index tree' (duration: 734.472862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:00.666307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:59.577697Z","time spent":"1.088603909s","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T19:32:00.666354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:59.931369Z","time spent":"734.967242ms","remote":"127.0.0.1:39520","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-25T19:32:00.66637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.208958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:00.666496Z","caller":"traceutil/trace.go:171","msg":"trace[1913233722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"415.372471ms","start":"2026-02-25T19:32:00.251102Z","end":"2026-02-25T19:32:00.666475Z","steps":["trace[1913233722] 'range keys from in-memory index tree' (duration: 415.119786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:00.666548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:00.251086Z","time spent":"415.452013ms","remote":"127.0.0.1:39256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:32:00.666669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.317217004s","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-25T19:32:00.666725Z","caller":"traceutil/trace.go:171","msg":"trace[352657297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"1.317310346s","start":"2026-02-25T19:31:59.349403Z","end":"2026-02-25T19:32:00.666714Z","steps":["trace[352657297] 'range keys from in-memory index tree' (duration: 1.317080201s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:00.666751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:31:59.34938Z","time spent":"1.317364358s","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:32:01.235072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345950209934486,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T19:32:01.32306Z","caller":"traceutil/trace.go:171","msg":"trace[138143981] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"650.730905ms","start":"2026-02-25T19:32:00.672291Z","end":"2026-02-25T19:32:01.323022Z","steps":["trace[138143981] 'process raft request' (duration: 650.0919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:01.323298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:00.672276Z","time spent":"650.874228ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:01.640082Z","caller":"traceutil/trace.go:171","msg":"trace[1341073901] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"967.196276ms","start":"2026-02-25T19:32:00.672861Z","end":"2026-02-25T19:32:01.640057Z","steps":["trace[1341073901] 'process raft request' (duration: 961.286677ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:01.640169Z","caller":"traceutil/trace.go:171","msg":"trace[1127652534] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:554; }","duration":"905.717918ms","start":"2026-02-25T19:32:00.73443Z","end":"2026-02-25T19:32:01.640148Z","steps":["trace[1127652534] 'read index received' (duration: 587.713472ms)","trace[1127652534] 'applied index is now lower than readState.Index' (duration: 318.003346ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:01.640255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:00.672837Z","time spent":"967.330599ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:01.640337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.912502ms","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-25T19:32:01.64034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.936571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:01.640359Z","caller":"traceutil/trace.go:171","msg":"trace[439156886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:542; }","duration":"905.996084ms","start":"2026-02-25T19:32:00.734357Z","end":"2026-02-25T19:32:01.640353Z","steps":["trace[439156886] 'agreement among raft nodes before linearized reading' (duration: 905.895162ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:01.640263Z","caller":"traceutil/trace.go:171","msg":"trace[1511616303] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"921.047474ms","start":"2026-02-25T19:32:00.719202Z","end":"2026-02-25T19:32:01.640249Z","steps":["trace[1511616303] 'process raft request' (duration: 920.799149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:01.640378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:00.734324Z","time spent":"906.049495ms","remote":"127.0.0.1:39520","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-25T19:32:01.640377Z","caller":"traceutil/trace.go:171","msg":"trace[592619286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"387.979062ms","start":"2026-02-25T19:32:01.25238Z","end":"2026-02-25T19:32:01.640359Z","steps":["trace[592619286] 'agreement among raft nodes before linearized reading' (duration: 387.91747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:01.640405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:01.252299Z","time spent":"388.101403ms","remote":"127.0.0.1:39256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:32:01.640429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:00.719164Z","time spent":"921.232327ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:13.901033Z","caller":"traceutil/trace.go:171","msg":"trace[769611830] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:703; }","duration":"183.961575ms","start":"2026-02-25T19:32:13.717044Z","end":"2026-02-25T19:32:13.901006Z","steps":["trace[769611830] 'read index received' (duration: 34.162509ms)","trace[769611830] 'applied index is now lower than readState.Index' (duration: 149.798356ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:13.901262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.198721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T19:32:13.901294Z","caller":"traceutil/trace.go:171","msg":"trace[188914744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:686; }","duration":"184.282952ms","start":"2026-02-25T19:32:13.717003Z","end":"2026-02-25T19:32:13.901286Z","steps":["trace[188914744] 'agreement among raft nodes before linearized reading' (duration: 184.106539ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:13.901717Z","caller":"traceutil/trace.go:171","msg":"trace[2055946925] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"200.027739ms","start":"2026-02-25T19:32:13.70168Z","end":"2026-02-25T19:32:13.901708Z","steps":["trace[2055946925] 'process raft request' (duration: 199.119658ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:13.901921Z","caller":"traceutil/trace.go:171","msg":"trace[180354663] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"198.973925ms","start":"2026-02-25T19:32:13.70294Z","end":"2026-02-25T19:32:13.901914Z","steps":["trace[180354663] 'process raft request' (duration: 198.015513ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:14.271488Z","caller":"traceutil/trace.go:171","msg":"trace[2030236358] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:706; }","duration":"362.780548ms","start":"2026-02-25T19:32:13.908682Z","end":"2026-02-25T19:32:14.271463Z","steps":["trace[2030236358] 'read index received' (duration: 357.817349ms)","trace[2030236358] 'applied index is now lower than readState.Index' (duration: 4.962289ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:14.271553Z","caller":"traceutil/trace.go:171","msg":"trace[1341333859] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"363.029653ms","start":"2026-02-25T19:32:13.908483Z","end":"2026-02-25T19:32:14.271513Z","steps":["trace[1341333859] 'process raft request' (duration: 362.850639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:14.271753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:13.908453Z","time spent":"363.194827ms","remote":"127.0.0.1:39522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1292,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:14.271828Z","caller":"traceutil/trace.go:171","msg":"trace[1734949948] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"363.277799ms","start":"2026-02-25T19:32:13.908536Z","end":"2026-02-25T19:32:14.271814Z","steps":["trace[1734949948] 'process raft request' (duration: 362.87919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:14.271973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:13.908507Z","time spent":"363.360461ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":783,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:14.272003Z","caller":"traceutil/trace.go:171","msg":"trace[1500564054] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"363.79434ms","start":"2026-02-25T19:32:13.908171Z","end":"2026-02-25T19:32:14.271966Z","steps":["trace[1500564054] 'process raft request' (duration: 358.31891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:14.272084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:13.908139Z","time spent":"363.907183ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:14.272169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.498933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-tczbg\" ","response":"range_response_count:1 size:4776"} {"level":"info","ts":"2026-02-25T19:32:14.272211Z","caller":"traceutil/trace.go:171","msg":"trace[562832765] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-tczbg; range_end:; response_count:1; response_revision:689; }","duration":"363.568085ms","start":"2026-02-25T19:32:13.90863Z","end":"2026-02-25T19:32:14.272198Z","steps":["trace[562832765] 'agreement among raft nodes before linearized reading' (duration: 363.446692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:14.272234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:13.908622Z","time spent":"363.607946ms","remote":"127.0.0.1:39434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4799,"request content":"key:\"/registry/pods/kube-system/coredns-67659f764b-tczbg\" "} {"level":"warn","ts":"2026-02-25T19:32:20.153993Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345950209934835,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:32:20.654248Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345950209934835,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:32:20.84548Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.41871204s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:32:20.845841Z","caller":"traceutil/trace.go:171","msg":"trace[616472275] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:747; }","duration":"1.192893888s","start":"2026-02-25T19:32:19.652923Z","end":"2026-02-25T19:32:20.845817Z","steps":["trace[616472275] 'read index received' (duration: 1.192698633s)","trace[616472275] 'applied index is now lower than readState.Index' (duration: 194.315µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:20.845992Z","caller":"traceutil/trace.go:171","msg":"trace[931960908] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"1.419373705s","start":"2026-02-25T19:32:19.426608Z","end":"2026-02-25T19:32:20.845981Z","steps":["trace[931960908] 'process raft request' (duration: 1.419067369s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:20.84618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:19.426573Z","time spent":"1.419509998s","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:20.846269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069035981s","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-25T19:32:20.846302Z","caller":"traceutil/trace.go:171","msg":"trace[1351575008] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:727; }","duration":"1.069096812s","start":"2026-02-25T19:32:19.777195Z","end":"2026-02-25T19:32:20.846292Z","steps":["trace[1351575008] 'agreement among raft nodes before linearized reading' (duration: 1.068939558s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:20.846327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:19.777178Z","time spent":"1.069144823s","remote":"127.0.0.1:39520","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-25T19:32:20.846425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193511971s","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-25T19:32:20.846474Z","caller":"traceutil/trace.go:171","msg":"trace[484862106] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:727; }","duration":"1.193597593s","start":"2026-02-25T19:32:19.652871Z","end":"2026-02-25T19:32:20.846468Z","steps":["trace[484862106] 'agreement among raft nodes before linearized reading' (duration: 1.193509571s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:20.846492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:19.652849Z","time spent":"1.193638743s","remote":"127.0.0.1:39520","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-25T19:32:20.846567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.776458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T19:32:20.846591Z","caller":"traceutil/trace.go:171","msg":"trace[1567621390] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:727; }","duration":"548.827239ms","start":"2026-02-25T19:32:20.297759Z","end":"2026-02-25T19:32:20.846586Z","steps":["trace[1567621390] 'agreement among raft nodes before linearized reading' (duration: 548.775228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:20.84661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:20.297742Z","time spent":"548.86375ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:32:20.846684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.651257ms","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-25T19:32:20.846698Z","caller":"traceutil/trace.go:171","msg":"trace[823186319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:727; }","duration":"916.702738ms","start":"2026-02-25T19:32:19.929991Z","end":"2026-02-25T19:32:20.846694Z","steps":["trace[823186319] 'agreement among raft nodes before linearized reading' (duration: 916.657398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:20.846715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:19.929969Z","time spent":"916.741279ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:32:20.846766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.785469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:20.846781Z","caller":"traceutil/trace.go:171","msg":"trace[2109876418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:727; }","duration":"590.8284ms","start":"2026-02-25T19:32:20.255948Z","end":"2026-02-25T19:32:20.846776Z","steps":["trace[2109876418] 'agreement among raft nodes before linearized reading' (duration: 590.80225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:20.846795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:20.255928Z","time spent":"590.863141ms","remote":"127.0.0.1:39256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:32:20.846838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.095453ms","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-25T19:32:20.846977Z","caller":"traceutil/trace.go:171","msg":"trace[1818610820] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:727; }","duration":"791.298597ms","start":"2026-02-25T19:32:20.055644Z","end":"2026-02-25T19:32:20.846943Z","steps":["trace[1818610820] 'agreement among raft nodes before linearized reading' (duration: 791.022481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:20.84706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:20.055624Z","time spent":"791.40487ms","remote":"127.0.0.1:39338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-02-25T19:32:53.22829Z","caller":"traceutil/trace.go:171","msg":"trace[564446846] linearizableReadLoop","detail":"{readStateIndex:850; appliedIndex:849; }","duration":"186.115993ms","start":"2026-02-25T19:32:53.042152Z","end":"2026-02-25T19:32:53.228268Z","steps":["trace[564446846] 'read index received' (duration: 186.00823ms)","trace[564446846] 'applied index is now lower than readState.Index' (duration: 106.483µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:53.22831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:52.543333Z","time spent":"684.971075ms","remote":"127.0.0.1:39294","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T19:32:53.228453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.286826ms","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-25T19:32:53.228477Z","caller":"traceutil/trace.go:171","msg":"trace[2029303835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:821; }","duration":"186.357828ms","start":"2026-02-25T19:32:53.042111Z","end":"2026-02-25T19:32:53.228469Z","steps":["trace[2029303835] 'agreement among raft nodes before linearized reading' (duration: 186.280646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:53.228794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.566389ms","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-25T19:32:53.228833Z","caller":"traceutil/trace.go:171","msg":"trace[1851353324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:821; }","duration":"164.651331ms","start":"2026-02-25T19:32:53.064166Z","end":"2026-02-25T19:32:53.228817Z","steps":["trace[1851353324] 'agreement among raft nodes before linearized reading' (duration: 164.508348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:53.228864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.273896ms","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-25T19:32:53.228872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.434541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:32:53.228928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.950463ms","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-25T19:32:53.228947Z","caller":"traceutil/trace.go:171","msg":"trace[998225810] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:821; }","duration":"156.989034ms","start":"2026-02-25T19:32:53.071952Z","end":"2026-02-25T19:32:53.228941Z","steps":["trace[998225810] 'agreement among raft nodes before linearized reading' (duration: 156.935193ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:53.228943Z","caller":"traceutil/trace.go:171","msg":"trace[370912743] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:821; }","duration":"104.566494ms","start":"2026-02-25T19:32:53.124361Z","end":"2026-02-25T19:32:53.228928Z","steps":["trace[370912743] 'agreement among raft nodes before linearized reading' (duration: 104.417721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:53.228967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.502971ms","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-25T19:32:53.228903Z","caller":"traceutil/trace.go:171","msg":"trace[1824498484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:821; }","duration":"150.339487ms","start":"2026-02-25T19:32:53.078554Z","end":"2026-02-25T19:32:53.228894Z","steps":["trace[1824498484] 'agreement among raft nodes before linearized reading' (duration: 150.214284ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:53.228997Z","caller":"traceutil/trace.go:171","msg":"trace[1136507100] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:821; }","duration":"160.581022ms","start":"2026-02-25T19:32:53.06841Z","end":"2026-02-25T19:32:53.228991Z","steps":["trace[1136507100] 'agreement among raft nodes before linearized reading' (duration: 160.511581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:54.754138Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345950209935133,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T19:32:54.950845Z","caller":"traceutil/trace.go:171","msg":"trace[447553623] linearizableReadLoop","detail":"{readStateIndex:857; appliedIndex:856; }","duration":"697.675943ms","start":"2026-02-25T19:32:54.253123Z","end":"2026-02-25T19:32:54.950799Z","steps":["trace[447553623] 'read index received' (duration: 638.448075ms)","trace[447553623] 'applied index is now lower than readState.Index' (duration: 59.226878ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:32:54.950925Z","caller":"traceutil/trace.go:171","msg":"trace[975823943] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"962.471842ms","start":"2026-02-25T19:32:53.988421Z","end":"2026-02-25T19:32:54.950893Z","steps":["trace[975823943] 'process raft request' (duration: 903.211963ms)","trace[975823943] 'compare' (duration: 58.511303ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:54.951038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.900278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:32:54.951074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:53.988397Z","time spent":"962.593466ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:32:54.951095Z","caller":"traceutil/trace.go:171","msg":"trace[1093333823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:828; }","duration":"697.999761ms","start":"2026-02-25T19:32:54.253082Z","end":"2026-02-25T19:32:54.951082Z","steps":["trace[1093333823] 'agreement among raft nodes before linearized reading' (duration: 697.909329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:54.951095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.062079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T19:32:54.951166Z","caller":"traceutil/trace.go:171","msg":"trace[786258503] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:828; }","duration":"469.170241ms","start":"2026-02-25T19:32:54.481979Z","end":"2026-02-25T19:32:54.951149Z","steps":["trace[786258503] 'agreement among raft nodes before linearized reading' (duration: 469.027228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:54.951121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:54.253063Z","time spent":"698.052782ms","remote":"127.0.0.1:39256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:32:54.951249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:54.481963Z","time spent":"469.269433ms","remote":"127.0.0.1:39580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:32:55.777538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.638208ms","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-25T19:32:55.777673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.24484ms","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-25T19:32:55.777765Z","caller":"traceutil/trace.go:171","msg":"trace[1055658284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:828; }","duration":"462.363972ms","start":"2026-02-25T19:32:55.315379Z","end":"2026-02-25T19:32:55.777743Z","steps":["trace[1055658284] 'range keys from in-memory index tree' (duration: 461.921123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:55.777788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.502794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-25T19:32:55.777815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:55.314854Z","time spent":"462.949315ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:32:55.777474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.639548ms","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-25T19:32:55.777857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.634798ms","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-25T19:32:55.77787Z","caller":"traceutil/trace.go:171","msg":"trace[1127620583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:828; }","duration":"461.626556ms","start":"2026-02-25T19:32:55.316221Z","end":"2026-02-25T19:32:55.777848Z","steps":["trace[1127620583] 'range keys from in-memory index tree' (duration: 461.32772ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:55.777886Z","caller":"traceutil/trace.go:171","msg":"trace[1069178207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:828; }","duration":"463.123269ms","start":"2026-02-25T19:32:55.314752Z","end":"2026-02-25T19:32:55.777875Z","steps":["trace[1069178207] 'range keys from in-memory index tree' (duration: 461.828181ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:55.777907Z","caller":"traceutil/trace.go:171","msg":"trace[266793638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:828; }","duration":"462.682799ms","start":"2026-02-25T19:32:55.315211Z","end":"2026-02-25T19:32:55.777894Z","steps":["trace[266793638] 'range keys from in-memory index tree' (duration: 462.128587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:55.777915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:55.314735Z","time spent":"463.172729ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:32:55.777941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:55.314767Z","time spent":"463.167339ms","remote":"127.0.0.1:39520","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-25T19:32:55.777919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:55.316211Z","time spent":"461.695997ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T19:32:55.778417Z","caller":"traceutil/trace.go:171","msg":"trace[1106375789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:828; }","duration":"462.831752ms","start":"2026-02-25T19:32:55.314821Z","end":"2026-02-25T19:32:55.777652Z","steps":["trace[1106375789] 'range keys from in-memory index tree' (duration: 461.713188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:55.778522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:55.3148Z","time spent":"463.706151ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T19:32:55.928678Z","caller":"traceutil/trace.go:171","msg":"trace[1517449518] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"146.418841ms","start":"2026-02-25T19:32:55.782225Z","end":"2026-02-25T19:32:55.928643Z","steps":["trace[1517449518] 'process raft request' (duration: 146.053673ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:55.928675Z","caller":"traceutil/trace.go:171","msg":"trace[1674267501] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"146.176596ms","start":"2026-02-25T19:32:55.782468Z","end":"2026-02-25T19:32:55.928645Z","steps":["trace[1674267501] 'process raft request' (duration: 146.084104ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:55.928823Z","caller":"traceutil/trace.go:171","msg":"trace[1525085827] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"145.301338ms","start":"2026-02-25T19:32:55.783497Z","end":"2026-02-25T19:32:55.928798Z","steps":["trace[1525085827] 'process raft request' (duration: 145.096123ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:55.928852Z","caller":"traceutil/trace.go:171","msg":"trace[1103256496] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"146.30408ms","start":"2026-02-25T19:32:55.782405Z","end":"2026-02-25T19:32:55.928709Z","steps":["trace[1103256496] 'process raft request' (duration: 146.096195ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:57.11813Z","caller":"traceutil/trace.go:171","msg":"trace[1637946429] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"301.006303ms","start":"2026-02-25T19:32:56.817099Z","end":"2026-02-25T19:32:57.118105Z","steps":["trace[1637946429] 'process raft request' (duration: 300.85128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:57.11828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:56.817072Z","time spent":"301.128086ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:32:58.313795Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345950209935154,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:32:58.525142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.272929332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:58.525225Z","caller":"traceutil/trace.go:171","msg":"trace[732084367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:834; }","duration":"1.273061076s","start":"2026-02-25T19:32:57.252145Z","end":"2026-02-25T19:32:58.525206Z","steps":["trace[732084367] 'range keys from in-memory index tree' (duration: 1.27283246s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:58.525268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:57.252121Z","time spent":"1.273137148s","remote":"127.0.0.1:39256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:32:58.525248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098109098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T19:32:58.525338Z","caller":"traceutil/trace.go:171","msg":"trace[1379732597] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:834; }","duration":"1.098255581s","start":"2026-02-25T19:32:57.42706Z","end":"2026-02-25T19:32:58.525315Z","steps":["trace[1379732597] 'count revisions from in-memory index tree' (duration: 1.098020347s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:58.525394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:57.427038Z","time spent":"1.098339513s","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:32:58.599495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.163624ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-25T19:32:58.600058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.316069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T19:32:58.600168Z","caller":"traceutil/trace.go:171","msg":"trace[535685725] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:834; }","duration":"786.471293ms","start":"2026-02-25T19:32:57.813669Z","end":"2026-02-25T19:32:58.60014Z","steps":["trace[535685725] 'agreement among raft nodes before linearized reading' (duration: 786.116135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:58.600239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:57.813651Z","time spent":"786.572534ms","remote":"127.0.0.1:39520","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-25T19:32:58.599822Z","caller":"traceutil/trace.go:171","msg":"trace[1312607599] linearizableReadLoop","detail":"{readStateIndex:864; appliedIndex:863; }","duration":"785.909909ms","start":"2026-02-25T19:32:57.813707Z","end":"2026-02-25T19:32:58.599617Z","steps":["trace[1312607599] 'read index received' (duration: 500.611692ms)","trace[1312607599] 'applied index is now lower than readState.Index' (duration: 285.295657ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:58.600832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.414305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T19:32:58.600987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.819917ms","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-25T19:32:58.601055Z","caller":"traceutil/trace.go:171","msg":"trace[469762537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:834; }","duration":"644.655311ms","start":"2026-02-25T19:32:57.956387Z","end":"2026-02-25T19:32:58.601042Z","steps":["trace[469762537] 'agreement among raft nodes before linearized reading' (duration: 644.346584ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:58.601067Z","caller":"traceutil/trace.go:171","msg":"trace[1570116093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:834; }","duration":"645.941639ms","start":"2026-02-25T19:32:57.955107Z","end":"2026-02-25T19:32:58.601049Z","steps":["trace[1570116093] 'agreement among raft nodes before linearized reading' (duration: 645.718424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:58.601106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:57.956378Z","time spent":"644.708172ms","remote":"127.0.0.1:39520","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-25T19:32:58.601112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:57.95508Z","time spent":"646.023111ms","remote":"127.0.0.1:39520","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-25T19:32:58.600889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.800126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T19:32:58.601175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.043792ms","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-25T19:32:58.601204Z","caller":"traceutil/trace.go:171","msg":"trace[442038913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:834; }","duration":"646.139554ms","start":"2026-02-25T19:32:57.955033Z","end":"2026-02-25T19:32:58.601172Z","steps":["trace[442038913] 'agreement among raft nodes before linearized reading' (duration: 645.654813ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:58.601257Z","caller":"traceutil/trace.go:171","msg":"trace[2064637012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:834; }","duration":"646.162794ms","start":"2026-02-25T19:32:57.955072Z","end":"2026-02-25T19:32:58.601235Z","steps":["trace[2064637012] 'agreement among raft nodes before linearized reading' (duration: 645.900149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:32:58.601305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:57.955056Z","time spent":"646.240516ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:32:58.601307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:32:57.955017Z","time spent":"646.251936ms","remote":"127.0.0.1:39520","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-25T19:32:58.833306Z","caller":"traceutil/trace.go:171","msg":"trace[856590727] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"227.210843ms","start":"2026-02-25T19:32:58.606071Z","end":"2026-02-25T19:32:58.833282Z","steps":["trace[856590727] 'process raft request' (duration: 227.131182ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:58.833439Z","caller":"traceutil/trace.go:171","msg":"trace[810215460] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"227.430279ms","start":"2026-02-25T19:32:58.60597Z","end":"2026-02-25T19:32:58.8334Z","steps":["trace[810215460] 'process raft request' (duration: 227.01411ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:58.833567Z","caller":"traceutil/trace.go:171","msg":"trace[421167770] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"227.207233ms","start":"2026-02-25T19:32:58.606306Z","end":"2026-02-25T19:32:58.833513Z","steps":["trace[421167770] 'process raft request' (duration: 226.942268ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:58.833592Z","caller":"traceutil/trace.go:171","msg":"trace[1425738793] linearizableReadLoop","detail":"{readStateIndex:869; appliedIndex:865; }","duration":"224.082016ms","start":"2026-02-25T19:32:58.609493Z","end":"2026-02-25T19:32:58.833575Z","steps":["trace[1425738793] 'read index received' (duration: 64.165098ms)","trace[1425738793] 'applied index is now lower than readState.Index' (duration: 159.916268ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:32:58.8337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.194548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:32:58.833727Z","caller":"traceutil/trace.go:171","msg":"trace[1567913211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:839; }","duration":"224.265099ms","start":"2026-02-25T19:32:58.609456Z","end":"2026-02-25T19:32:58.833721Z","steps":["trace[1567913211] 'agreement among raft nodes before linearized reading' (duration: 224.184287ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:32:58.833715Z","caller":"traceutil/trace.go:171","msg":"trace[148440500] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"226.389956ms","start":"2026-02-25T19:32:58.6073Z","end":"2026-02-25T19:32:58.83369Z","steps":["trace[148440500] 'process raft request' (duration: 226.179792ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:01.043926Z","caller":"traceutil/trace.go:171","msg":"trace[269612342] linearizableReadLoop","detail":"{readStateIndex:870; appliedIndex:869; }","duration":"204.836243ms","start":"2026-02-25T19:33:00.839071Z","end":"2026-02-25T19:33:01.043907Z","steps":["trace[269612342] 'read index received' (duration: 204.666219ms)","trace[269612342] 'applied index is now lower than readState.Index' (duration: 169.224µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:01.044046Z","caller":"traceutil/trace.go:171","msg":"trace[1753885094] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"360.639651ms","start":"2026-02-25T19:33:00.683371Z","end":"2026-02-25T19:33:01.044011Z","steps":["trace[1753885094] 'process raft request' (duration: 360.420166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:01.044115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.029447ms","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-25T19:33:01.044152Z","caller":"traceutil/trace.go:171","msg":"trace[1561677022] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:840; }","duration":"205.127629ms","start":"2026-02-25T19:33:00.839016Z","end":"2026-02-25T19:33:01.044144Z","steps":["trace[1561677022] 'agreement among raft nodes before linearized reading' (duration: 204.987286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:01.044234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:00.683341Z","time spent":"360.779545ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:01.04429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.011307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T19:33:01.044364Z","caller":"traceutil/trace.go:171","msg":"trace[1197278061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:840; }","duration":"205.238321ms","start":"2026-02-25T19:33:00.839108Z","end":"2026-02-25T19:33:01.044346Z","steps":["trace[1197278061] 'agreement among raft nodes before linearized reading' (duration: 205.054407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:01.044572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.897174ms","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-25T19:33:01.044637Z","caller":"traceutil/trace.go:171","msg":"trace[628413077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:840; }","duration":"204.982156ms","start":"2026-02-25T19:33:00.83964Z","end":"2026-02-25T19:33:01.044622Z","steps":["trace[628413077] 'agreement among raft nodes before linearized reading' (duration: 204.771692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:01.044698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.187689ms","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-25T19:33:01.044861Z","caller":"traceutil/trace.go:171","msg":"trace[1973912012] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:840; }","duration":"204.413864ms","start":"2026-02-25T19:33:00.840399Z","end":"2026-02-25T19:33:01.044813Z","steps":["trace[1973912012] 'agreement among raft nodes before linearized reading' (duration: 203.871062ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:01.398323Z","caller":"traceutil/trace.go:171","msg":"trace[889207878] linearizableReadLoop","detail":"{readStateIndex:874; appliedIndex:871; }","duration":"147.192139ms","start":"2026-02-25T19:33:01.251105Z","end":"2026-02-25T19:33:01.398297Z","steps":["trace[889207878] 'read index received' (duration: 146.828761ms)","trace[889207878] 'applied index is now lower than readState.Index' (duration: 362.368µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:01.398509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.389943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:01.398552Z","caller":"traceutil/trace.go:171","msg":"trace[2061826634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:844; }","duration":"147.473486ms","start":"2026-02-25T19:33:01.251065Z","end":"2026-02-25T19:33:01.398538Z","steps":["trace[2061826634] 'agreement among raft nodes before linearized reading' (duration: 147.336833ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:01.398681Z","caller":"traceutil/trace.go:171","msg":"trace[966990203] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"349.124938ms","start":"2026-02-25T19:33:01.049447Z","end":"2026-02-25T19:33:01.398572Z","steps":["trace[966990203] 'process raft request' (duration: 348.610646ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:01.398818Z","caller":"traceutil/trace.go:171","msg":"trace[2024762400] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"349.275822ms","start":"2026-02-25T19:33:01.04951Z","end":"2026-02-25T19:33:01.398785Z","steps":["trace[2024762400] 'process raft request' (duration: 348.693098ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:01.398931Z","caller":"traceutil/trace.go:171","msg":"trace[270866037] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"348.975996ms","start":"2026-02-25T19:33:01.049931Z","end":"2026-02-25T19:33:01.398907Z","steps":["trace[270866037] 'process raft request' (duration: 348.319751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:01.39898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:01.049435Z","time spent":"349.389195ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:01.39916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:01.049914Z","time spent":"349.117168ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:01.398947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:01.049502Z","time spent":"349.360234ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:11.823726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.93244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:11.823817Z","caller":"traceutil/trace.go:171","msg":"trace[1481205604] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:923; }","duration":"238.117783ms","start":"2026-02-25T19:33:11.585681Z","end":"2026-02-25T19:33:11.823799Z","steps":["trace[1481205604] 'count revisions from in-memory index tree' (duration: 237.799676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:13.663342Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345950209935361,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T19:33:14.116783Z","caller":"traceutil/trace.go:171","msg":"trace[918692085] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"953.489625ms","start":"2026-02-25T19:33:13.16327Z","end":"2026-02-25T19:33:14.116759Z","steps":["trace[918692085] 'read index received' (duration: 953.27185ms)","trace[918692085] 'applied index is now lower than readState.Index' (duration: 216.805µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:14.116857Z","caller":"traceutil/trace.go:171","msg":"trace[522571180] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"997.53654ms","start":"2026-02-25T19:33:13.11929Z","end":"2026-02-25T19:33:14.116826Z","steps":["trace[522571180] 'process raft request' (duration: 997.336596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:14.116999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:13.119277Z","time spent":"997.648042ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:14.117133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.495347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:33:14.117167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.027224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T19:33:14.117216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.674465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T19:33:14.117226Z","caller":"traceutil/trace.go:171","msg":"trace[296082499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"866.63307ms","start":"2026-02-25T19:33:13.25057Z","end":"2026-02-25T19:33:14.117203Z","steps":["trace[296082499] 'agreement among raft nodes before linearized reading' (duration: 866.464597ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:14.117269Z","caller":"traceutil/trace.go:171","msg":"trace[1715648141] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:925; }","duration":"656.778267ms","start":"2026-02-25T19:33:13.460477Z","end":"2026-02-25T19:33:14.117255Z","steps":["trace[1715648141] 'agreement among raft nodes before linearized reading' (duration: 656.634774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:14.117282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:13.250552Z","time spent":"866.717542ms","remote":"127.0.0.1:39256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:33:14.117302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:13.460451Z","time spent":"656.843848ms","remote":"127.0.0.1:39520","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-25T19:33:14.11722Z","caller":"traceutil/trace.go:171","msg":"trace[1724597708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:925; }","duration":"658.124086ms","start":"2026-02-25T19:33:13.459083Z","end":"2026-02-25T19:33:14.117207Z","steps":["trace[1724597708] 'agreement among raft nodes before linearized reading' (duration: 657.959693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:14.117298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.69915ms","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":"warn","ts":"2026-02-25T19:33:14.117363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:13.45907Z","time spent":"658.282439ms","remote":"127.0.0.1:39520","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-25T19:33:14.117405Z","caller":"traceutil/trace.go:171","msg":"trace[521013559] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:925; }","duration":"664.871413ms","start":"2026-02-25T19:33:13.45251Z","end":"2026-02-25T19:33:14.117381Z","steps":["trace[521013559] 'agreement among raft nodes before linearized reading' (duration: 664.573377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:14.117455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:13.452489Z","time spent":"664.955266ms","remote":"127.0.0.1:39520","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-25T19:33:14.117777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.508807ms","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-25T19:33:14.117848Z","caller":"traceutil/trace.go:171","msg":"trace[1834143282] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:925; }","duration":"954.611879ms","start":"2026-02-25T19:33:13.163223Z","end":"2026-02-25T19:33:14.117835Z","steps":["trace[1834143282] 'agreement among raft nodes before linearized reading' (duration: 953.671539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:14.117878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:13.163205Z","time spent":"954.665401ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:33:15.417019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058126361s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:33:15.417095Z","caller":"traceutil/trace.go:171","msg":"trace[1327215291] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"1.295550269s","start":"2026-02-25T19:33:14.121531Z","end":"2026-02-25T19:33:15.417081Z","steps":["trace[1327215291] 'process raft request' (duration: 237.303706ms)","trace[1327215291] 'compare' (duration: 1.057672701s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:15.417136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:14.121509Z","time spent":"1.295607641s","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:15.422577Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.063882877s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:33:15.422888Z","caller":"traceutil/trace.go:171","msg":"trace[772112829] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:960; }","duration":"1.171046498s","start":"2026-02-25T19:33:14.251829Z","end":"2026-02-25T19:33:15.422876Z","steps":["trace[772112829] 'read index received' (duration: 107.019388ms)","trace[772112829] 'applied index is now lower than readState.Index' (duration: 1.06402637s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:15.422959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17112847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:15.422956Z","caller":"traceutil/trace.go:171","msg":"trace[1856868386] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"1.301276765s","start":"2026-02-25T19:33:14.121669Z","end":"2026-02-25T19:33:15.422946Z","steps":["trace[1856868386] 'process raft request' (duration: 1.301160232s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:15.422978Z","caller":"traceutil/trace.go:171","msg":"trace[1020537836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"1.17115287s","start":"2026-02-25T19:33:14.251819Z","end":"2026-02-25T19:33:15.422972Z","steps":["trace[1020537836] 'agreement among raft nodes before linearized reading' (duration: 1.171098269s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:15.422998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:14.251772Z","time spent":"1.171221612s","remote":"127.0.0.1:39256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:33:15.423016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:14.12166Z","time spent":"1.301323207s","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:33:15.423084Z","caller":"traceutil/trace.go:171","msg":"trace[1199546948] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"1.301083301s","start":"2026-02-25T19:33:14.121994Z","end":"2026-02-25T19:33:15.423077Z","steps":["trace[1199546948] 'process raft request' (duration: 1.300858346s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:15.423117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:14.121985Z","time spent":"1.301112892s","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:33:15.422922Z","caller":"traceutil/trace.go:171","msg":"trace[262283037] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"1.301282166s","start":"2026-02-25T19:33:14.121618Z","end":"2026-02-25T19:33:15.4229Z","steps":["trace[262283037] 'process raft request' (duration: 1.301146233s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:15.423359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:14.121588Z","time spent":"1.301745165s","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:33:15.452864Z","caller":"traceutil/trace.go:171","msg":"trace[462450833] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"157.193279ms","start":"2026-02-25T19:33:15.295644Z","end":"2026-02-25T19:33:15.452837Z","steps":["trace[462450833] 'process raft request' (duration: 156.705557ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:35.667612Z","caller":"traceutil/trace.go:171","msg":"trace[1653960899] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"116.518219ms","start":"2026-02-25T19:33:35.551069Z","end":"2026-02-25T19:33:35.667587Z","steps":["trace[1653960899] 'read index received' (duration: 116.316435ms)","trace[1653960899] 'applied index is now lower than readState.Index' (duration: 200.744µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:35.667972Z","caller":"traceutil/trace.go:171","msg":"trace[2040134165] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"138.076764ms","start":"2026-02-25T19:33:35.529877Z","end":"2026-02-25T19:33:35.667954Z","steps":["trace[2040134165] 'process raft request' (duration: 137.578464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:35.668272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.176764ms","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-25T19:33:35.668283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.151813ms","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-25T19:33:35.668351Z","caller":"traceutil/trace.go:171","msg":"trace[1016679425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:990; }","duration":"117.287175ms","start":"2026-02-25T19:33:35.551042Z","end":"2026-02-25T19:33:35.668329Z","steps":["trace[1016679425] 'agreement among raft nodes before linearized reading' (duration: 117.065091ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:35.66841Z","caller":"traceutil/trace.go:171","msg":"trace[1860038830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:990; }","duration":"117.266855ms","start":"2026-02-25T19:33:35.551092Z","end":"2026-02-25T19:33:35.668359Z","steps":["trace[1860038830] 'agreement among raft nodes before linearized reading' (duration: 117.0194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:35.669274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.693215ms","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-25T19:33:35.669315Z","caller":"traceutil/trace.go:171","msg":"trace[1406435991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:990; }","duration":"117.745296ms","start":"2026-02-25T19:33:35.55156Z","end":"2026-02-25T19:33:35.669305Z","steps":["trace[1406435991] 'agreement among raft nodes before linearized reading' (duration: 116.621331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:36.951109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.879805ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:33:36.951421Z","caller":"traceutil/trace.go:171","msg":"trace[959469118] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"1.279690731s","start":"2026-02-25T19:33:35.671697Z","end":"2026-02-25T19:33:36.951388Z","steps":["trace[959469118] 'process raft request' (duration: 684.398947ms)","trace[959469118] 'compare' (duration: 594.281351ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:36.95154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:35.671672Z","time spent":"1.279825444s","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:33:37.18491Z","caller":"traceutil/trace.go:171","msg":"trace[184449533] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"1.512007674s","start":"2026-02-25T19:33:35.67288Z","end":"2026-02-25T19:33:37.184888Z","steps":["trace[184449533] 'process raft request' (duration: 1.511932182s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:37.185052Z","caller":"traceutil/trace.go:171","msg":"trace[581733518] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"1.512422122s","start":"2026-02-25T19:33:35.672592Z","end":"2026-02-25T19:33:37.185014Z","steps":["trace[581733518] 'process raft request' (duration: 1.511813059s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:37.185362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.370809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T19:33:37.185074Z","caller":"traceutil/trace.go:171","msg":"trace[1043248991] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1029; }","duration":"944.086433ms","start":"2026-02-25T19:33:36.240962Z","end":"2026-02-25T19:33:37.185048Z","steps":["trace[1043248991] 'read index received' (duration: 115.150609ms)","trace[1043248991] 'applied index is now lower than readState.Index' (duration: 828.934614ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:37.185414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:35.672576Z","time spent":"1.512752819s","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:37.185629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.889451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:37.185363Z","caller":"traceutil/trace.go:171","msg":"trace[1661939202] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"1.158474488s","start":"2026-02-25T19:33:36.026865Z","end":"2026-02-25T19:33:37.18534Z","steps":["trace[1661939202] 'process raft request' (duration: 1.157987718s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:37.185682Z","caller":"traceutil/trace.go:171","msg":"trace[9381037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:994; }","duration":"920.976833ms","start":"2026-02-25T19:33:36.264695Z","end":"2026-02-25T19:33:37.185671Z","steps":["trace[9381037] 'agreement among raft nodes before linearized reading' (duration: 920.883811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:37.185717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:36.26468Z","time spent":"921.029364ms","remote":"127.0.0.1:39256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:33:37.18575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:36.026847Z","time spent":"1.158846597s","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:37.185799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.022454ms","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-25T19:33:37.185945Z","caller":"traceutil/trace.go:171","msg":"trace[542927928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:994; }","duration":"921.232149ms","start":"2026-02-25T19:33:36.2647Z","end":"2026-02-25T19:33:37.185932Z","steps":["trace[542927928] 'agreement among raft nodes before linearized reading' (duration: 920.896671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:37.185993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:36.264681Z","time spent":"921.301321ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T19:33:37.185466Z","caller":"traceutil/trace.go:171","msg":"trace[741557274] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:994; }","duration":"944.527072ms","start":"2026-02-25T19:33:36.240927Z","end":"2026-02-25T19:33:37.185454Z","steps":["trace[741557274] 'agreement among raft nodes before linearized reading' (duration: 944.365728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:37.186097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:36.240911Z","time spent":"945.175998ms","remote":"127.0.0.1:39718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:33:37.185107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:35.672864Z","time spent":"1.512121646s","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:39.692749Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345950209935545,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:33:39.972381Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.010924237s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:33:39.972934Z","caller":"traceutil/trace.go:171","msg":"trace[910116991] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"781.12924ms","start":"2026-02-25T19:33:39.19177Z","end":"2026-02-25T19:33:39.972899Z","steps":["trace[910116991] 'read index received' (duration: 780.967426ms)","trace[910116991] 'applied index is now lower than readState.Index' (duration: 158.024µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:39.973046Z","caller":"traceutil/trace.go:171","msg":"trace[197205454] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"1.011698673s","start":"2026-02-25T19:33:38.96131Z","end":"2026-02-25T19:33:39.973008Z","steps":["trace[197205454] 'process raft request' (duration: 1.011336315s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:39.973177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:38.96128Z","time spent":"1.011818617s","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:39.97357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.794445ms","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-25T19:33:39.973636Z","caller":"traceutil/trace.go:171","msg":"trace[158307501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:998; }","duration":"781.920809ms","start":"2026-02-25T19:33:39.191704Z","end":"2026-02-25T19:33:39.973625Z","steps":["trace[158307501] 'agreement among raft nodes before linearized reading' (duration: 781.325725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:39.973663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:39.191672Z","time spent":"781.983419ms","remote":"127.0.0.1:39520","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-25T19:33:40.129676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.307597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:33:40.129836Z","caller":"traceutil/trace.go:171","msg":"trace[1661295523] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"150.865326ms","start":"2026-02-25T19:33:39.978956Z","end":"2026-02-25T19:33:40.129821Z","steps":["trace[1661295523] 'process raft request' (duration: 150.810434ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:40.129897Z","caller":"traceutil/trace.go:171","msg":"trace[3595695] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"156.839247ms","start":"2026-02-25T19:33:39.973022Z","end":"2026-02-25T19:33:40.129862Z","steps":["trace[3595695] 'read index received' (duration: 19.308936ms)","trace[3595695] 'applied index is now lower than readState.Index' (duration: 137.524771ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:40.12988Z","caller":"traceutil/trace.go:171","msg":"trace[80425501] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"276.236009ms","start":"2026-02-25T19:33:39.85358Z","end":"2026-02-25T19:33:40.129816Z","steps":["trace[80425501] 'process raft request' (duration: 138.736588ms)","trace[80425501] 'compare' (duration: 137.166764ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:40.130226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.477897ms","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-25T19:33:40.130297Z","caller":"traceutil/trace.go:171","msg":"trace[1184864451] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1000; }","duration":"443.642912ms","start":"2026-02-25T19:33:39.686643Z","end":"2026-02-25T19:33:40.130286Z","steps":["trace[1184864451] 'agreement among raft nodes before linearized reading' (duration: 443.321555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:40.130363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:39.686623Z","time spent":"443.707833ms","remote":"127.0.0.1:39520","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-25T19:33:40.130427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.454579ms","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-25T19:33:40.130479Z","caller":"traceutil/trace.go:171","msg":"trace[864579948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1000; }","duration":"938.558762ms","start":"2026-02-25T19:33:39.191911Z","end":"2026-02-25T19:33:40.13047Z","steps":["trace[864579948] 'agreement among raft nodes before linearized reading' (duration: 938.420269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:40.130471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.721578ms","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-25T19:33:40.130506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:39.191884Z","time spent":"938.614653ms","remote":"127.0.0.1:39520","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-25T19:33:40.130517Z","caller":"traceutil/trace.go:171","msg":"trace[1490134907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1000; }","duration":"920.847521ms","start":"2026-02-25T19:33:39.209656Z","end":"2026-02-25T19:33:40.130504Z","steps":["trace[1490134907] 'agreement among raft nodes before linearized reading' (duration: 920.580156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:40.130566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:39.209639Z","time spent":"920.910762ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T19:33:40.279845Z","caller":"traceutil/trace.go:171","msg":"trace[1810320137] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"141.493029ms","start":"2026-02-25T19:33:40.138326Z","end":"2026-02-25T19:33:40.279819Z","steps":["trace[1810320137] 'process raft request' (duration: 124.535516ms)","trace[1810320137] 'compare' (duration: 16.765729ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:40.280117Z","caller":"traceutil/trace.go:171","msg":"trace[1797755492] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"141.12258ms","start":"2026-02-25T19:33:40.138977Z","end":"2026-02-25T19:33:40.280099Z","steps":["trace[1797755492] 'process raft request' (duration: 140.794873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:41.951321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.996445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5183"} {"level":"info","ts":"2026-02-25T19:33:41.951407Z","caller":"traceutil/trace.go:171","msg":"trace[571748952] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1004; }","duration":"149.19315ms","start":"2026-02-25T19:33:41.802192Z","end":"2026-02-25T19:33:41.951385Z","steps":["trace[571748952] 'range keys from in-memory index tree' (duration: 148.807862ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:42.108491Z","caller":"traceutil/trace.go:171","msg":"trace[1583829084] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1044; }","duration":"131.051809ms","start":"2026-02-25T19:33:41.977421Z","end":"2026-02-25T19:33:42.108472Z","steps":["trace[1583829084] 'read index received' (duration: 130.63462ms)","trace[1583829084] 'applied index is now lower than readState.Index' (duration: 416.559µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:42.108699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.260074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T19:33:42.108753Z","caller":"traceutil/trace.go:171","msg":"trace[1569372882] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1005; }","duration":"131.361806ms","start":"2026-02-25T19:33:41.977383Z","end":"2026-02-25T19:33:42.108745Z","steps":["trace[1569372882] 'agreement among raft nodes before linearized reading' (duration: 131.162212ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:42.108801Z","caller":"traceutil/trace.go:171","msg":"trace[447257892] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"140.432715ms","start":"2026-02-25T19:33:41.96834Z","end":"2026-02-25T19:33:42.108772Z","steps":["trace[447257892] 'process raft request' (duration: 140.019246ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:42.24109Z","caller":"traceutil/trace.go:171","msg":"trace[151939714] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"123.951493ms","start":"2026-02-25T19:33:42.117109Z","end":"2026-02-25T19:33:42.24106Z","steps":["trace[151939714] 'process raft request' (duration: 123.737558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:42.241263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.428478ms","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-25T19:33:42.241311Z","caller":"traceutil/trace.go:171","msg":"trace[1429518870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1007; }","duration":"107.549121ms","start":"2026-02-25T19:33:42.133752Z","end":"2026-02-25T19:33:42.241301Z","steps":["trace[1429518870] 'agreement among raft nodes before linearized reading' (duration: 107.327966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:42.594362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.927297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:33:42.594575Z","caller":"traceutil/trace.go:171","msg":"trace[1526402589] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"336.893407ms","start":"2026-02-25T19:33:42.25767Z","end":"2026-02-25T19:33:42.594563Z","steps":["trace[1526402589] 'process raft request' (duration: 336.854686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:42.594641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:42.257653Z","time spent":"336.953708ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:33:42.594647Z","caller":"traceutil/trace.go:171","msg":"trace[1238818156] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"349.681758ms","start":"2026-02-25T19:33:42.244935Z","end":"2026-02-25T19:33:42.594616Z","steps":["trace[1238818156] 'process raft request' (duration: 51.425504ms)","trace[1238818156] 'compare' (duration: 297.822745ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:42.594781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.800011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:33:42.594787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:42.24491Z","time spent":"349.799341ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:33:42.594815Z","caller":"traceutil/trace.go:171","msg":"trace[1826578297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"344.874742ms","start":"2026-02-25T19:33:42.249934Z","end":"2026-02-25T19:33:42.594808Z","steps":["trace[1826578297] 'agreement among raft nodes before linearized reading' (duration: 344.76103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:42.594837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:42.249916Z","time spent":"344.916223ms","remote":"127.0.0.1:39268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:33:42.59467Z","caller":"traceutil/trace.go:171","msg":"trace[1015426794] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1049; }","duration":"342.52549ms","start":"2026-02-25T19:33:42.252131Z","end":"2026-02-25T19:33:42.594657Z","steps":["trace[1015426794] 'read index received' (duration: 44.241465ms)","trace[1015426794] 'applied index is now lower than readState.Index' (duration: 298.282545ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:42.595029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.706185ms","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-25T19:33:42.595055Z","caller":"traceutil/trace.go:171","msg":"trace[1747319705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1011; }","duration":"303.776497ms","start":"2026-02-25T19:33:42.291271Z","end":"2026-02-25T19:33:42.595047Z","steps":["trace[1747319705] 'agreement among raft nodes before linearized reading' (duration: 303.690365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:42.595081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:42.291254Z","time spent":"303.822317ms","remote":"127.0.0.1:39520","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-25T19:33:42.595231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.258446ms","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-25T19:33:42.595329Z","caller":"traceutil/trace.go:171","msg":"trace[1365910657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1011; }","duration":"303.395149ms","start":"2026-02-25T19:33:42.291912Z","end":"2026-02-25T19:33:42.595307Z","steps":["trace[1365910657] 'agreement among raft nodes before linearized reading' (duration: 303.129653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:42.59537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:42.291899Z","time spent":"303.463221ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T19:33:44.646019Z","caller":"traceutil/trace.go:171","msg":"trace[1596353011] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1068; }","duration":"394.983878ms","start":"2026-02-25T19:33:44.251012Z","end":"2026-02-25T19:33:44.645996Z","steps":["trace[1596353011] 'read index received' (duration: 394.700391ms)","trace[1596353011] 'applied index is now lower than readState.Index' (duration: 282.547µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:44.646676Z","caller":"traceutil/trace.go:171","msg":"trace[490452611] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"398.044255ms","start":"2026-02-25T19:33:44.248619Z","end":"2026-02-25T19:33:44.646664Z","steps":["trace[490452611] 'process raft request' (duration: 397.177727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:44.646769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:44.248588Z","time spent":"398.117416ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:33:44.646845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.846276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:44.646869Z","caller":"traceutil/trace.go:171","msg":"trace[2090012431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1028; }","duration":"395.901058ms","start":"2026-02-25T19:33:44.25096Z","end":"2026-02-25T19:33:44.646861Z","steps":["trace[2090012431] 'agreement among raft nodes before linearized reading' (duration: 395.860687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:44.646887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:44.250946Z","time spent":"395.936828ms","remote":"127.0.0.1:39256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:33:44.933191Z","caller":"traceutil/trace.go:171","msg":"trace[1513705479] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"281.868354ms","start":"2026-02-25T19:33:44.651299Z","end":"2026-02-25T19:33:44.933168Z","steps":["trace[1513705479] 'process raft request' (duration: 191.87101ms)","trace[1513705479] 'compare' (duration: 89.81339ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:33:44.947449Z","caller":"traceutil/trace.go:171","msg":"trace[1259469839] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"293.775547ms","start":"2026-02-25T19:33:44.653654Z","end":"2026-02-25T19:33:44.94743Z","steps":["trace[1259469839] 'process raft request' (duration: 293.747236ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:44.947703Z","caller":"traceutil/trace.go:171","msg":"trace[2105830438] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"295.117566ms","start":"2026-02-25T19:33:44.652574Z","end":"2026-02-25T19:33:44.947692Z","steps":["trace[2105830438] 'process raft request' (duration: 294.802589ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:44.947666Z","caller":"traceutil/trace.go:171","msg":"trace[1179244060] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"295.141496ms","start":"2026-02-25T19:33:44.652488Z","end":"2026-02-25T19:33:44.94763Z","steps":["trace[1179244060] 'process raft request' (duration: 294.793189ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:47.061726Z","caller":"traceutil/trace.go:171","msg":"trace[1826750576] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1081; }","duration":"111.069829ms","start":"2026-02-25T19:33:46.950627Z","end":"2026-02-25T19:33:47.061697Z","steps":["trace[1826750576] 'read index received' (duration: 110.860375ms)","trace[1826750576] 'applied index is now lower than readState.Index' (duration: 207.894µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:47.061983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.327365ms","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-25T19:33:47.062045Z","caller":"traceutil/trace.go:171","msg":"trace[119199504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1041; }","duration":"111.426257ms","start":"2026-02-25T19:33:46.950601Z","end":"2026-02-25T19:33:47.062028Z","steps":["trace[119199504] 'agreement among raft nodes before linearized reading' (duration: 111.238843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:47.06204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.199809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T19:33:47.062085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.909386ms","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-25T19:33:47.06219Z","caller":"traceutil/trace.go:171","msg":"trace[572304161] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1041; }","duration":"111.061439ms","start":"2026-02-25T19:33:46.95111Z","end":"2026-02-25T19:33:47.062171Z","steps":["trace[572304161] 'agreement among raft nodes before linearized reading' (duration: 110.785613ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:47.062123Z","caller":"traceutil/trace.go:171","msg":"trace[1088878709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1041; }","duration":"110.316361ms","start":"2026-02-25T19:33:46.951792Z","end":"2026-02-25T19:33:47.062109Z","steps":["trace[1088878709] 'agreement among raft nodes before linearized reading' (duration: 110.122217ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:33:47.062355Z","caller":"traceutil/trace.go:171","msg":"trace[1244843213] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"121.123309ms","start":"2026-02-25T19:33:46.940707Z","end":"2026-02-25T19:33:47.061831Z","steps":["trace[1244843213] 'process raft request' (duration: 120.832973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:48.168813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.925592ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T19:33:48.169224Z","caller":"traceutil/trace.go:171","msg":"trace[360969455] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1090; }","duration":"423.745572ms","start":"2026-02-25T19:33:47.745407Z","end":"2026-02-25T19:33:48.169152Z","steps":["trace[360969455] 'read index received' (duration: 30.39954ms)","trace[360969455] 'applied index is now lower than readState.Index' (duration: 393.340312ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:33:48.169472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.859963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:48.169508Z","caller":"traceutil/trace.go:171","msg":"trace[694327042] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1050; }","duration":"271.989266ms","start":"2026-02-25T19:33:47.897508Z","end":"2026-02-25T19:33:48.169497Z","steps":["trace[694327042] 'agreement among raft nodes before linearized reading' (duration: 271.832203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:48.169533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.103699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:33:48.170022Z","caller":"traceutil/trace.go:171","msg":"trace[1376370605] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1050; }","duration":"424.404786ms","start":"2026-02-25T19:33:47.745371Z","end":"2026-02-25T19:33:48.169776Z","steps":["trace[1376370605] 'agreement among raft nodes before linearized reading' (duration: 423.936036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:33:48.170288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:33:47.745353Z","time spent":"424.732134ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T19:37:02.775121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.888904ms","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:532"} {"level":"info","ts":"2026-02-25T19:37:02.775302Z","caller":"traceutil/trace.go:171","msg":"trace[1723298173] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1897; }","duration":"162.111689ms","start":"2026-02-25T19:37:02.61317Z","end":"2026-02-25T19:37:02.775282Z","steps":["trace[1723298173] 'range keys from in-memory index tree' (duration: 161.70605ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:37:03.186953Z","caller":"traceutil/trace.go:171","msg":"trace[1528507091] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"407.357735ms","start":"2026-02-25T19:37:02.779563Z","end":"2026-02-25T19:37:03.18692Z","steps":["trace[1528507091] 'process raft request' (duration: 407.085809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:37:03.187132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:37:02.779539Z","time spent":"407.504098ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}