{"level":"warn","ts":"2026-03-18T00:46:08.472897Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T00:46:08.472989Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242: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.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242: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-03-18T00:46:08.473056Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T00:46:08.473069Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-03-18T00:46:08.473085Z","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-03-18T00:46:08.473687Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-03-18T00:46:08.473766Z","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.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242: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-03-18T00:46:08.482399Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.557749ms"} {"level":"info","ts":"2026-03-18T00:46:08.494414Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-03-18T00:46:08.494513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-03-18T00:46:08.494538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-03-18T00:46:08.494557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-18T00:46:08.494569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-03-18T00:46:08.494611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-03-18T00:46:08.51098Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-18T00:46:08.512572Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-18T00:46:08.514456Z","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-03-18T00:46:08.516444Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-18T00:46:08.516969Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-18T00:46:08.518574Z","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-03-18T00:46:08.519605Z","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-03-18T00:46:08.520638Z","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-03-18T00:46:08.520917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-03-18T00:46:08.52099Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-03-18T00:46:08.521754Z","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-03-18T00:46:08.521864Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-03-18T00:46:08.521896Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-03-18T00:46:08.521971Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-18T00:46:08.521997Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-18T00:46:09.394775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-03-18T00:46:09.394826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-18T00:46:09.394856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-03-18T00:46:09.394879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-03-18T00:46:09.394887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-03-18T00:46:09.394898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-03-18T00:46:09.39491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-03-18T00:46:09.395698Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-03-18T00:46:09.395687Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T00:46:09.395755Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T00:46:09.395785Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T00:46:09.395941Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-18T00:46:09.395976Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-18T00:46:09.396565Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T00:46:09.396669Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T00:46:09.396686Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T00:46:09.39763Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-03-18T00:46:09.399769Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-18T00:46:11.166042Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113069157281395,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-18T00:46:11.357427Z","caller":"traceutil/trace.go:171","msg":"trace[1872918464] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"692.940982ms","start":"2026-03-18T00:46:10.66446Z","end":"2026-03-18T00:46:11.357401Z","steps":["trace[1872918464] 'process raft request' (duration: 692.750488ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:11.358043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:10.66445Z","time spent":"693.062805ms","remote":"127.0.0.1:53884","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-03-18T00:46:11.958702Z","caller":"traceutil/trace.go:171","msg":"trace[220948838] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.208215164s","start":"2026-03-18T00:46:10.750445Z","end":"2026-03-18T00:46:11.95866Z","steps":["trace[220948838] 'process raft request' (duration: 1.18910785s)","trace[220948838] 'compare' (duration: 18.822857ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:46:11.958798Z","caller":"traceutil/trace.go:171","msg":"trace[1778190987] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.207746483s","start":"2026-03-18T00:46:10.750999Z","end":"2026-03-18T00:46:11.958746Z","steps":["trace[1778190987] 'process raft request' (duration: 1.207588659s)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:11.958831Z","caller":"traceutil/trace.go:171","msg":"trace[898489712] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:4; }","duration":"1.293162902s","start":"2026-03-18T00:46:10.665645Z","end":"2026-03-18T00:46:11.958808Z","steps":["trace[898489712] 'read index received' (duration: 691.668352ms)","trace[898489712] 'applied index is now lower than readState.Index' (duration: 601.49324ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:46:11.958931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:10.750429Z","time spent":"1.208365747s","remote":"127.0.0.1:54300","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-03-18T00:46:11.959471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.293749876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-18T00:46:11.959582Z","caller":"traceutil/trace.go:171","msg":"trace[1078324222] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:10; }","duration":"1.29393274s","start":"2026-03-18T00:46:10.665624Z","end":"2026-03-18T00:46:11.959557Z","steps":["trace[1078324222] 'agreement among raft nodes before linearized reading' (duration: 1.293611542s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:11.959637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:10.665616Z","time spent":"1.294011362s","remote":"127.0.0.1:53992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-03-18T00:46:11.958977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:10.750987Z","time spent":"1.207900326s","remote":"127.0.0.1:54372","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-03-18T00:46:11.959799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.28593535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-18T00:46:11.959859Z","caller":"traceutil/trace.go:171","msg":"trace[607663344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10; }","duration":"1.286011632s","start":"2026-03-18T00:46:10.673829Z","end":"2026-03-18T00:46:11.959841Z","steps":["trace[607663344] 'agreement among raft nodes before linearized reading' (duration: 1.285911619s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:11.959902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:10.673819Z","time spent":"1.286072343s","remote":"127.0.0.1:53836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T00:46:11.959002Z","caller":"traceutil/trace.go:171","msg":"trace[2017048943] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.198909872s","start":"2026-03-18T00:46:10.760065Z","end":"2026-03-18T00:46:11.958975Z","steps":["trace[2017048943] 'process raft request' (duration: 1.198666946s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:11.960116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.513217ms","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-03-18T00:46:11.960151Z","caller":"traceutil/trace.go:171","msg":"trace[1634258563] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:10; }","duration":"310.582949ms","start":"2026-03-18T00:46:11.64956Z","end":"2026-03-18T00:46:11.960143Z","steps":["trace[1634258563] 'agreement among raft nodes before linearized reading' (duration: 310.534948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:11.960157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210068828s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-18T00:46:11.96014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:10.760043Z","time spent":"1.199928867s","remote":"127.0.0.1:53976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T00:46:11.960212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211684827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-18T00:46:11.960222Z","caller":"traceutil/trace.go:171","msg":"trace[631251926] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.210130749s","start":"2026-03-18T00:46:10.750068Z","end":"2026-03-18T00:46:11.960199Z","steps":["trace[631251926] 'agreement among raft nodes before linearized reading' (duration: 1.210035217s)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:11.960243Z","caller":"traceutil/trace.go:171","msg":"trace[349179558] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:0; response_revision:10; }","duration":"1.211746677s","start":"2026-03-18T00:46:10.748488Z","end":"2026-03-18T00:46:11.960235Z","steps":["trace[349179558] 'agreement among raft nodes before linearized reading' (duration: 1.211708166s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:11.960258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:10.750044Z","time spent":"1.210206701s","remote":"127.0.0.1:53914","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-03-18T00:46:11.960272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:10.748462Z","time spent":"1.211805729s","remote":"127.0.0.1:53850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.242\" "} {"level":"warn","ts":"2026-03-18T00:46:11.960332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157544269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-18T00:46:11.959045Z","caller":"traceutil/trace.go:171","msg":"trace[141540457] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.207829754s","start":"2026-03-18T00:46:10.751174Z","end":"2026-03-18T00:46:11.959004Z","steps":["trace[141540457] 'process raft request' (duration: 1.207455885s)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:11.960383Z","caller":"traceutil/trace.go:171","msg":"trace[1638637176] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.157595361s","start":"2026-03-18T00:46:10.802776Z","end":"2026-03-18T00:46:11.960371Z","steps":["trace[1638637176] 'agreement among raft nodes before linearized reading' (duration: 1.157524769s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:11.960423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:10.802759Z","time spent":"1.157657023s","remote":"127.0.0.1:53914","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-03-18T00:46:11.960435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209679929s","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-03-18T00:46:11.960444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:10.751157Z","time spent":"1.209226248s","remote":"127.0.0.1:54372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T00:46:11.960469Z","caller":"traceutil/trace.go:171","msg":"trace[956553925] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"1.20973246s","start":"2026-03-18T00:46:10.750724Z","end":"2026-03-18T00:46:11.960456Z","steps":["trace[956553925] 'agreement among raft nodes before linearized reading' (duration: 1.209685808s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:11.960501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.286449803s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"warn","ts":"2026-03-18T00:46:11.960178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:11.649533Z","time spent":"310.63958ms","remote":"127.0.0.1:54216","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-03-18T00:46:11.960536Z","caller":"traceutil/trace.go:171","msg":"trace[1606961076] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"1.286489123s","start":"2026-03-18T00:46:10.674038Z","end":"2026-03-18T00:46:11.960527Z","steps":["trace[1606961076] 'agreement among raft nodes before linearized reading' (duration: 1.286440142s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:11.960566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:10.674033Z","time spent":"1.286528654s","remote":"127.0.0.1:53976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3194,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-18T00:46:11.960586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.142352ms","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-03-18T00:46:11.959101Z","caller":"traceutil/trace.go:171","msg":"trace[217700723] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.181748775s","start":"2026-03-18T00:46:10.777338Z","end":"2026-03-18T00:46:11.959086Z","steps":["trace[217700723] 'process raft request' (duration: 1.181437557s)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:11.959128Z","caller":"traceutil/trace.go:171","msg":"trace[1595483075] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.207742782s","start":"2026-03-18T00:46:10.751369Z","end":"2026-03-18T00:46:11.959112Z","steps":["trace[1595483075] 'process raft request' (duration: 1.207311022s)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:11.960617Z","caller":"traceutil/trace.go:171","msg":"trace[124269945] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:10; }","duration":"311.210034ms","start":"2026-03-18T00:46:11.649398Z","end":"2026-03-18T00:46:11.960608Z","steps":["trace[124269945] 'agreement among raft nodes before linearized reading' (duration: 311.164543ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:11.960647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:11.649377Z","time spent":"311.263305ms","remote":"127.0.0.1:54192","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":"warn","ts":"2026-03-18T00:46:11.960509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:10.750713Z","time spent":"1.209788421s","remote":"127.0.0.1:53902","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-03-18T00:46:11.960459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281885794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-18T00:46:11.960754Z","caller":"traceutil/trace.go:171","msg":"trace[1336957175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:10; }","duration":"1.282200651s","start":"2026-03-18T00:46:10.678544Z","end":"2026-03-18T00:46:11.960744Z","steps":["trace[1336957175] 'agreement among raft nodes before linearized reading' (duration: 1.281898394s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:11.960796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139081221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-18T00:46:11.960799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:10.67853Z","time spent":"1.282259032s","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T00:46:11.960808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:10.777322Z","time spent":"1.183297161s","remote":"127.0.0.1:54074","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-03-18T00:46:11.960832Z","caller":"traceutil/trace.go:171","msg":"trace[411079393] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"1.139160453s","start":"2026-03-18T00:46:10.821663Z","end":"2026-03-18T00:46:11.960824Z","steps":["trace[411079393] 'agreement among raft nodes before linearized reading' (duration: 1.139113112s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:11.960871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:10.821639Z","time spent":"1.139216455s","remote":"127.0.0.1:53914","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-03-18T00:46:11.960424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281162017s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-18T00:46:11.960974Z","caller":"traceutil/trace.go:171","msg":"trace[308578911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:10; }","duration":"1.28171176s","start":"2026-03-18T00:46:10.679253Z","end":"2026-03-18T00:46:11.960965Z","steps":["trace[308578911] 'agreement among raft nodes before linearized reading' (duration: 1.281147056s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:11.960834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:10.751355Z","time spent":"1.209295039s","remote":"127.0.0.1:54372","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-03-18T00:46:11.961001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:10.679204Z","time spent":"1.281789841s","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-18T00:46:11.959249Z","caller":"traceutil/trace.go:171","msg":"trace[1334965547] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.207560628s","start":"2026-03-18T00:46:10.751661Z","end":"2026-03-18T00:46:11.959222Z","steps":["trace[1334965547] 'process raft request' (duration: 1.207041616s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:11.961122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:10.75165Z","time spent":"1.209434433s","remote":"127.0.0.1:54372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T00:46:11.959155Z","caller":"traceutil/trace.go:171","msg":"trace[361007745] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.207794094s","start":"2026-03-18T00:46:10.751284Z","end":"2026-03-18T00:46:11.959078Z","steps":["trace[361007745] 'process raft request' (duration: 1.207370244s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:11.96133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:10.751276Z","time spent":"1.209936045s","remote":"127.0.0.1:54372","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-03-18T00:46:12.524277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.434621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-03-18T00:46:12.524523Z","caller":"traceutil/trace.go:171","msg":"trace[1939534977] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"559.027802ms","start":"2026-03-18T00:46:11.965455Z","end":"2026-03-18T00:46:12.524483Z","steps":["trace[1939534977] 'process raft request' (duration: 353.153061ms)","trace[1939534977] 'compare' (duration: 205.312797ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:46:12.524702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:11.965437Z","time spent":"559.170565ms","remote":"127.0.0.1:53888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:46:12.524686Z","caller":"traceutil/trace.go:171","msg":"trace[2139471078] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"559.032552ms","start":"2026-03-18T00:46:11.965621Z","end":"2026-03-18T00:46:12.524654Z","steps":["trace[2139471078] 'process raft request' (duration: 558.764965ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:12.524823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:11.965603Z","time spent":"559.159094ms","remote":"127.0.0.1:53914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T00:46:12.524911Z","caller":"traceutil/trace.go:171","msg":"trace[1038968295] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"558.360925ms","start":"2026-03-18T00:46:11.966522Z","end":"2026-03-18T00:46:12.524883Z","steps":["trace[1038968295] 'process raft request' (duration: 558.101989ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:12.525097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:11.966509Z","time spent":"558.510619ms","remote":"127.0.0.1:54216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T00:46:12.525154Z","caller":"traceutil/trace.go:171","msg":"trace[811397481] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"559.374879ms","start":"2026-03-18T00:46:11.965765Z","end":"2026-03-18T00:46:12.52514Z","steps":["trace[811397481] 'process raft request' (duration: 558.815596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:12.525231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:11.965759Z","time spent":"559.437841ms","remote":"127.0.0.1:54372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T00:46:12.525359Z","caller":"traceutil/trace.go:171","msg":"trace[631851314] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"557.820033ms","start":"2026-03-18T00:46:11.967527Z","end":"2026-03-18T00:46:12.525347Z","steps":["trace[631851314] 'process raft request' (duration: 557.205869ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:12.5254Z","caller":"traceutil/trace.go:171","msg":"trace[2042208430] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:13; }","duration":"559.701877ms","start":"2026-03-18T00:46:11.965676Z","end":"2026-03-18T00:46:12.525378Z","steps":["trace[2042208430] 'read index received' (duration: 353.028567ms)","trace[2042208430] 'applied index is now lower than readState.Index' (duration: 206.67203ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:46:12.525502Z","caller":"traceutil/trace.go:171","msg":"trace[409686584] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"557.917275ms","start":"2026-03-18T00:46:11.967574Z","end":"2026-03-18T00:46:12.525491Z","steps":["trace[409686584] 'process raft request' (duration: 557.272559ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:12.525521Z","caller":"traceutil/trace.go:171","msg":"trace[1231608999] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"558.217592ms","start":"2026-03-18T00:46:11.967294Z","end":"2026-03-18T00:46:12.525512Z","steps":["trace[1231608999] 'process raft request' (duration: 557.354742ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:12.525565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:11.96728Z","time spent":"558.265423ms","remote":"127.0.0.1:54248","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-03-18T00:46:12.525568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:11.96756Z","time spent":"557.976007ms","remote":"127.0.0.1:54372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T00:46:12.525696Z","caller":"traceutil/trace.go:171","msg":"trace[584766654] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"558.065008ms","start":"2026-03-18T00:46:11.967616Z","end":"2026-03-18T00:46:12.525681Z","steps":["trace[584766654] 'process raft request' (duration: 557.357771ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:12.525741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:11.967607Z","time spent":"558.11603ms","remote":"127.0.0.1:54372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T00:46:12.525818Z","caller":"traceutil/trace.go:171","msg":"trace[374743342] transaction","detail":"{read_only:false; number_of_response:0; response_revision:18; }","duration":"557.835333ms","start":"2026-03-18T00:46:11.967967Z","end":"2026-03-18T00:46:12.525803Z","steps":["trace[374743342] 'process raft request' (duration: 557.040894ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:12.525842Z","caller":"traceutil/trace.go:171","msg":"trace[831856423] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"553.822857ms","start":"2026-03-18T00:46:11.971989Z","end":"2026-03-18T00:46:12.525812Z","steps":["trace[831856423] 'process raft request' (duration: 553.121121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:12.525901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:11.967954Z","time spent":"557.911885ms","remote":"127.0.0.1:53914","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-03-18T00:46:12.525938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.256231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-18T00:46:12.525937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:11.97198Z","time spent":"553.90456ms","remote":"127.0.0.1:53976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:46:12.525979Z","caller":"traceutil/trace.go:171","msg":"trace[524580390] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:21; }","duration":"560.317372ms","start":"2026-03-18T00:46:11.965652Z","end":"2026-03-18T00:46:12.52597Z","steps":["trace[524580390] 'agreement among raft nodes before linearized reading' (duration: 560.202709ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:12.526003Z","caller":"traceutil/trace.go:171","msg":"trace[56663297] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"520.783153ms","start":"2026-03-18T00:46:12.005213Z","end":"2026-03-18T00:46:12.525996Z","steps":["trace[56663297] 'process raft request' (duration: 519.973073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:12.526017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:11.965645Z","time spent":"560.362823ms","remote":"127.0.0.1:54206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-03-18T00:46:12.526088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.394614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-18T00:46:12.526155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.258026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-18T00:46:12.526176Z","caller":"traceutil/trace.go:171","msg":"trace[1493327992] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"503.297858ms","start":"2026-03-18T00:46:12.022873Z","end":"2026-03-18T00:46:12.526171Z","steps":["trace[1493327992] 'agreement among raft nodes before linearized reading' (duration: 503.257947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:12.526191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:12.022857Z","time spent":"503.330668ms","remote":"127.0.0.1:53914","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":"warn","ts":"2026-03-18T00:46:12.526203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.88623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-03-18T00:46:12.525421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:11.96747Z","time spent":"557.924795ms","remote":"127.0.0.1:54300","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-03-18T00:46:12.526299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.094205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-18T00:46:12.526328Z","caller":"traceutil/trace.go:171","msg":"trace[508393019] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"509.149716ms","start":"2026-03-18T00:46:12.017172Z","end":"2026-03-18T00:46:12.526322Z","steps":["trace[508393019] 'agreement among raft nodes before linearized reading' (duration: 509.104465ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:12.526339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.109148ms","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-03-18T00:46:12.526355Z","caller":"traceutil/trace.go:171","msg":"trace[1174315014] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:21; }","duration":"358.142889ms","start":"2026-03-18T00:46:12.168206Z","end":"2026-03-18T00:46:12.526349Z","steps":["trace[1174315014] 'agreement among raft nodes before linearized reading' (duration: 358.116768ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:12.526355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:12.01716Z","time spent":"509.189327ms","remote":"127.0.0.1:53914","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":"warn","ts":"2026-03-18T00:46:12.526372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:12.168194Z","time spent":"358.17432ms","remote":"127.0.0.1:54090","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":"info","ts":"2026-03-18T00:46:12.52583Z","caller":"traceutil/trace.go:171","msg":"trace[1686478005] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"520.161578ms","start":"2026-03-18T00:46:12.005657Z","end":"2026-03-18T00:46:12.525818Z","steps":["trace[1686478005] 'process raft request' (duration: 519.675107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:12.526456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:12.005633Z","time spent":"520.807843ms","remote":"127.0.0.1:54372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T00:46:12.526151Z","caller":"traceutil/trace.go:171","msg":"trace[1584999833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21; }","duration":"560.470136ms","start":"2026-03-18T00:46:11.965667Z","end":"2026-03-18T00:46:12.526137Z","steps":["trace[1584999833] 'agreement among raft nodes before linearized reading' (duration: 560.368814ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:12.526498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:11.96566Z","time spent":"560.835254ms","remote":"127.0.0.1:53828","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-03-18T00:46:12.526027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:12.005195Z","time spent":"520.821484ms","remote":"127.0.0.1:54372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T00:46:12.526228Z","caller":"traceutil/trace.go:171","msg":"trace[418415930] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"508.940151ms","start":"2026-03-18T00:46:12.017281Z","end":"2026-03-18T00:46:12.526221Z","steps":["trace[418415930] 'agreement among raft nodes before linearized reading' (duration: 508.88994ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:12.526587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:12.017264Z","time spent":"509.320021ms","remote":"127.0.0.1:53914","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-03-18T00:46:38.895287Z","caller":"traceutil/trace.go:171","msg":"trace[235939809] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"394.084428ms","start":"2026-03-18T00:46:38.501178Z","end":"2026-03-18T00:46:38.895262Z","steps":["trace[235939809] 'process raft request' (duration: 393.936674ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:38.895447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:38.50117Z","time spent":"394.200972ms","remote":"127.0.0.1:54090","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-03-18T00:46:39.011753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.007919ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-18T00:46:39.01185Z","caller":"traceutil/trace.go:171","msg":"trace[996491758] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"341.220358ms","start":"2026-03-18T00:46:38.670614Z","end":"2026-03-18T00:46:39.011834Z","steps":["trace[996491758] 'read index received' (duration: 225.247331ms)","trace[996491758] 'applied index is now lower than readState.Index' (duration: 115.971767ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:46:39.011958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.335511ms","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-03-18T00:46:39.011991Z","caller":"traceutil/trace.go:171","msg":"trace[217775383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:468; }","duration":"341.410573ms","start":"2026-03-18T00:46:38.67057Z","end":"2026-03-18T00:46:39.011981Z","steps":["trace[217775383] 'agreement among raft nodes before linearized reading' (duration: 341.334571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:39.011998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.221573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-03-18T00:46:39.012027Z","caller":"traceutil/trace.go:171","msg":"trace[1613112631] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:468; }","duration":"109.276685ms","start":"2026-03-18T00:46:38.902742Z","end":"2026-03-18T00:46:39.012018Z","steps":["trace[1613112631] 'agreement among raft nodes before linearized reading' (duration: 109.228004ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:39.012027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:38.670549Z","time spent":"341.470345ms","remote":"127.0.0.1:54090","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-03-18T00:46:39.012212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.075666ms","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-03-18T00:46:39.012232Z","caller":"traceutil/trace.go:171","msg":"trace[209816058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:468; }","duration":"339.107938ms","start":"2026-03-18T00:46:38.673119Z","end":"2026-03-18T00:46:39.012227Z","steps":["trace[209816058] 'agreement among raft nodes before linearized reading' (duration: 338.812301ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:39.012244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:38.673111Z","time spent":"339.130078ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-18T00:46:39.152664Z","caller":"traceutil/trace.go:171","msg":"trace[2011728960] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"135.748305ms","start":"2026-03-18T00:46:39.016891Z","end":"2026-03-18T00:46:39.152639Z","steps":["trace[2011728960] 'process raft request' (duration: 135.556171ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:39.153289Z","caller":"traceutil/trace.go:171","msg":"trace[606454615] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"134.059185ms","start":"2026-03-18T00:46:39.019172Z","end":"2026-03-18T00:46:39.153231Z","steps":["trace[606454615] 'process raft request' (duration: 133.400829ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:45.042273Z","caller":"traceutil/trace.go:171","msg":"trace[1499807633] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"122.013753ms","start":"2026-03-18T00:46:44.920212Z","end":"2026-03-18T00:46:45.042226Z","steps":["trace[1499807633] 'process raft request' (duration: 121.881409ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:45.602559Z","caller":"traceutil/trace.go:171","msg":"trace[1557208648] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"251.016985ms","start":"2026-03-18T00:46:45.351516Z","end":"2026-03-18T00:46:45.602533Z","steps":["trace[1557208648] 'process raft request' (duration: 250.796759ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:46.581571Z","caller":"traceutil/trace.go:171","msg":"trace[942206895] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:504; }","duration":"303.805903ms","start":"2026-03-18T00:46:46.277742Z","end":"2026-03-18T00:46:46.581548Z","steps":["trace[942206895] 'read index received' (duration: 303.546426ms)","trace[942206895] 'applied index is now lower than readState.Index' (duration: 258.607µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:46:46.581693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.926915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:46:46.581733Z","caller":"traceutil/trace.go:171","msg":"trace[1291612979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:491; }","duration":"304.025918ms","start":"2026-03-18T00:46:46.277697Z","end":"2026-03-18T00:46:46.581723Z","steps":["trace[1291612979] 'agreement among raft nodes before linearized reading' (duration: 303.938776ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:46.581738Z","caller":"traceutil/trace.go:171","msg":"trace[597733186] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"690.554574ms","start":"2026-03-18T00:46:45.891048Z","end":"2026-03-18T00:46:46.581602Z","steps":["trace[597733186] 'process raft request' (duration: 690.340809ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:46.58193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:45.891025Z","time spent":"690.80538ms","remote":"127.0.0.1:53982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:46:46.581765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:46.277676Z","time spent":"304.08211ms","remote":"127.0.0.1:53836","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-03-18T00:46:47.60264Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113069157282358,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T00:46:48.103237Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113069157282358,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T00:46:48.603788Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113069157282358,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T00:46:48.703749Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.654101043s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T00:46:48.704278Z","caller":"traceutil/trace.go:171","msg":"trace[60702518] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.654725638s","start":"2026-03-18T00:46:47.049525Z","end":"2026-03-18T00:46:48.704251Z","steps":["trace[60702518] 'process raft request' (duration: 1.654557004s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:48.70445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:47.049496Z","time spent":"1.654867152s","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:46:48.70853Z","caller":"traceutil/trace.go:171","msg":"trace[1178642841] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:505; }","duration":"1.606393608s","start":"2026-03-18T00:46:47.102115Z","end":"2026-03-18T00:46:48.708509Z","steps":["trace[1178642841] 'read index received' (duration: 1.601985132s)","trace[1178642841] 'applied index is now lower than readState.Index' (duration: 4.406956ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:46:48.708673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.606548032s","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-03-18T00:46:48.708685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.528269437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-18T00:46:48.708711Z","caller":"traceutil/trace.go:171","msg":"trace[1345680017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:492; }","duration":"1.606638264s","start":"2026-03-18T00:46:47.102064Z","end":"2026-03-18T00:46:48.708702Z","steps":["trace[1345680017] 'agreement among raft nodes before linearized reading' (duration: 1.606544362s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:48.708711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.889395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:46:48.708748Z","caller":"traceutil/trace.go:171","msg":"trace[347764555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:492; }","duration":"1.528384301s","start":"2026-03-18T00:46:47.18035Z","end":"2026-03-18T00:46:48.708734Z","steps":["trace[347764555] 'agreement among raft nodes before linearized reading' (duration: 1.528281318s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:48.708754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:47.102043Z","time spent":"1.606703455s","remote":"127.0.0.1:54090","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-03-18T00:46:48.708748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102142574s","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-03-18T00:46:48.708785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:47.180322Z","time spent":"1.528455372s","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-18T00:46:48.708784Z","caller":"traceutil/trace.go:171","msg":"trace[1525515817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:492; }","duration":"822.988398ms","start":"2026-03-18T00:46:47.885777Z","end":"2026-03-18T00:46:48.708765Z","steps":["trace[1525515817] 'agreement among raft nodes before linearized reading' (duration: 822.883665ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:48.708802Z","caller":"traceutil/trace.go:171","msg":"trace[192810840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"1.102232076s","start":"2026-03-18T00:46:47.606555Z","end":"2026-03-18T00:46:48.708787Z","steps":["trace[192810840] 'agreement among raft nodes before linearized reading' (duration: 1.102121053s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:48.708836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:47.885761Z","time spent":"823.063269ms","remote":"127.0.0.1:53836","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-03-18T00:46:48.708852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:46:47.606516Z","time spent":"1.102324978s","remote":"127.0.0.1:54090","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-03-18T00:46:50.162075Z","caller":"traceutil/trace.go:171","msg":"trace[1730583063] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"145.573153ms","start":"2026-03-18T00:46:50.016482Z","end":"2026-03-18T00:46:50.162055Z","steps":["trace[1730583063] 'process raft request' (duration: 145.45927ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:50.439885Z","caller":"traceutil/trace.go:171","msg":"trace[1996463768] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:522; }","duration":"264.387189ms","start":"2026-03-18T00:46:50.175472Z","end":"2026-03-18T00:46:50.439859Z","steps":["trace[1996463768] 'read index received' (duration: 264.315418ms)","trace[1996463768] 'applied index is now lower than readState.Index' (duration: 70.461µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:46:50.440154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.654696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:46:50.440219Z","caller":"traceutil/trace.go:171","msg":"trace[1059824281] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:508; }","duration":"264.746098ms","start":"2026-03-18T00:46:50.175457Z","end":"2026-03-18T00:46:50.440203Z","steps":["trace[1059824281] 'agreement among raft nodes before linearized reading' (duration: 264.587604ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:50.440191Z","caller":"traceutil/trace.go:171","msg":"trace[1782595044] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"264.874791ms","start":"2026-03-18T00:46:50.175172Z","end":"2026-03-18T00:46:50.440047Z","steps":["trace[1782595044] 'process raft request' (duration: 264.569833ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:50.446668Z","caller":"traceutil/trace.go:171","msg":"trace[639818010] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"269.772529ms","start":"2026-03-18T00:46:50.176881Z","end":"2026-03-18T00:46:50.446654Z","steps":["trace[639818010] 'process raft request' (duration: 269.734918ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:50.44682Z","caller":"traceutil/trace.go:171","msg":"trace[772705891] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"271.267955ms","start":"2026-03-18T00:46:50.175525Z","end":"2026-03-18T00:46:50.446793Z","steps":["trace[772705891] 'process raft request' (duration: 271.05325ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:50.447093Z","caller":"traceutil/trace.go:171","msg":"trace[1924052644] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"271.570763ms","start":"2026-03-18T00:46:50.175502Z","end":"2026-03-18T00:46:50.447073Z","steps":["trace[1924052644] 'process raft request' (duration: 271.02631ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:50.447144Z","caller":"traceutil/trace.go:171","msg":"trace[1961432920] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"271.563722ms","start":"2026-03-18T00:46:50.175563Z","end":"2026-03-18T00:46:50.447127Z","steps":["trace[1961432920] 'process raft request' (duration: 271.034549ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:50.893278Z","caller":"traceutil/trace.go:171","msg":"trace[1236593433] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"169.920483ms","start":"2026-03-18T00:46:50.723333Z","end":"2026-03-18T00:46:50.893254Z","steps":["trace[1236593433] 'read index received' (duration: 169.81024ms)","trace[1236593433] 'applied index is now lower than readState.Index' (duration: 109.213µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:46:50.893375Z","caller":"traceutil/trace.go:171","msg":"trace[1061531482] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"181.009751ms","start":"2026-03-18T00:46:50.712339Z","end":"2026-03-18T00:46:50.893349Z","steps":["trace[1061531482] 'process raft request' (duration: 180.763575ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:50.893553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.201659ms","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-03-18T00:46:50.89363Z","caller":"traceutil/trace.go:171","msg":"trace[1072198528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"170.320692ms","start":"2026-03-18T00:46:50.723283Z","end":"2026-03-18T00:46:50.893604Z","steps":["trace[1072198528] 'agreement among raft nodes before linearized reading' (duration: 170.094347ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:50.914667Z","caller":"traceutil/trace.go:171","msg":"trace[1359501993] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"142.463968ms","start":"2026-03-18T00:46:50.772177Z","end":"2026-03-18T00:46:50.914641Z","steps":["trace[1359501993] 'process raft request' (duration: 142.325535ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:50.914782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.418069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-18T00:46:50.914876Z","caller":"traceutil/trace.go:171","msg":"trace[139927436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"190.531921ms","start":"2026-03-18T00:46:50.724333Z","end":"2026-03-18T00:46:50.914865Z","steps":["trace[139927436] 'agreement among raft nodes before linearized reading' (duration: 190.334636ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:50.914808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.319846ms","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-03-18T00:46:50.914981Z","caller":"traceutil/trace.go:171","msg":"trace[1600733608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:524; }","duration":"190.51246ms","start":"2026-03-18T00:46:50.724454Z","end":"2026-03-18T00:46:50.914966Z","steps":["trace[1600733608] 'agreement among raft nodes before linearized reading' (duration: 190.172532ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:51.039378Z","caller":"traceutil/trace.go:171","msg":"trace[1162743031] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"138.83839ms","start":"2026-03-18T00:46:50.900515Z","end":"2026-03-18T00:46:51.039354Z","steps":["trace[1162743031] 'process raft request' (duration: 138.641235ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:51.039533Z","caller":"traceutil/trace.go:171","msg":"trace[272647558] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:540; }","duration":"124.907003ms","start":"2026-03-18T00:46:50.914612Z","end":"2026-03-18T00:46:51.039519Z","steps":["trace[272647558] 'read index received' (duration: 124.521833ms)","trace[272647558] 'applied index is now lower than readState.Index' (duration: 384.03µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:46:51.039601Z","caller":"traceutil/trace.go:171","msg":"trace[1388780437] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"132.604759ms","start":"2026-03-18T00:46:50.90697Z","end":"2026-03-18T00:46:51.039575Z","steps":["trace[1388780437] 'process raft request' (duration: 132.337583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:51.039783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.060997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-b6bgc\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-03-18T00:46:51.039953Z","caller":"traceutil/trace.go:171","msg":"trace[1076550920] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-b6bgc; range_end:; response_count:1; response_revision:526; }","duration":"137.118109ms","start":"2026-03-18T00:46:50.902689Z","end":"2026-03-18T00:46:51.039808Z","steps":["trace[1076550920] 'agreement among raft nodes before linearized reading' (duration: 136.921834ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:51.16049Z","caller":"traceutil/trace.go:171","msg":"trace[1136869602] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"240.388858ms","start":"2026-03-18T00:46:50.920088Z","end":"2026-03-18T00:46:51.160477Z","steps":["trace[1136869602] 'process raft request' (duration: 240.320137ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:51.160482Z","caller":"traceutil/trace.go:171","msg":"trace[789776529] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"240.408279ms","start":"2026-03-18T00:46:50.920046Z","end":"2026-03-18T00:46:51.160455Z","steps":["trace[789776529] 'process raft request' (duration: 240.015959ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:51.160665Z","caller":"traceutil/trace.go:171","msg":"trace[894770841] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:542; }","duration":"121.06697ms","start":"2026-03-18T00:46:51.039591Z","end":"2026-03-18T00:46:51.160658Z","steps":["trace[894770841] 'read index received' (duration: 120.582738ms)","trace[894770841] 'applied index is now lower than readState.Index' (duration: 483.542µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:46:51.160773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.687445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:46:51.160802Z","caller":"traceutil/trace.go:171","msg":"trace[638282877] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:529; }","duration":"190.726476ms","start":"2026-03-18T00:46:50.970067Z","end":"2026-03-18T00:46:51.160793Z","steps":["trace[638282877] 'agreement among raft nodes before linearized reading' (duration: 190.638974ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:51.160958Z","caller":"traceutil/trace.go:171","msg":"trace[693506671] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"239.858565ms","start":"2026-03-18T00:46:50.921078Z","end":"2026-03-18T00:46:51.160936Z","steps":["trace[693506671] 'process raft request' (duration: 239.361643ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:51.232075Z","caller":"traceutil/trace.go:171","msg":"trace[2042445415] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"183.961523ms","start":"2026-03-18T00:46:51.048102Z","end":"2026-03-18T00:46:51.232064Z","steps":["trace[2042445415] 'process raft request' (duration: 183.903772ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:51.339765Z","caller":"traceutil/trace.go:171","msg":"trace[1715445505] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"172.052784ms","start":"2026-03-18T00:46:51.167684Z","end":"2026-03-18T00:46:51.339736Z","steps":["trace[1715445505] 'process raft request' (duration: 166.799967ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:51.33996Z","caller":"traceutil/trace.go:171","msg":"trace[1885587277] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"170.400604ms","start":"2026-03-18T00:46:51.169294Z","end":"2026-03-18T00:46:51.339694Z","steps":["trace[1885587277] 'read index received' (duration: 62.948433ms)","trace[1885587277] 'applied index is now lower than readState.Index' (duration: 107.449851ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:46:51.340065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.750733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:46:51.340239Z","caller":"traceutil/trace.go:171","msg":"trace[1272074186] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:531; }","duration":"170.945798ms","start":"2026-03-18T00:46:51.169273Z","end":"2026-03-18T00:46:51.340219Z","steps":["trace[1272074186] 'agreement among raft nodes before linearized reading' (duration: 170.737273ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:51.340499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.806776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xvkxn\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-03-18T00:46:51.340578Z","caller":"traceutil/trace.go:171","msg":"trace[1945907177] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xvkxn; range_end:; response_count:1; response_revision:531; }","duration":"104.916649ms","start":"2026-03-18T00:46:51.235618Z","end":"2026-03-18T00:46:51.340534Z","steps":["trace[1945907177] 'agreement among raft nodes before linearized reading' (duration: 104.51117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:53.405067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.222744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-18T00:46:53.40522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.420919ms","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-03-18T00:46:53.40527Z","caller":"traceutil/trace.go:171","msg":"trace[188364184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:560; }","duration":"240.43079ms","start":"2026-03-18T00:46:53.164782Z","end":"2026-03-18T00:46:53.405213Z","steps":["trace[188364184] 'range keys from in-memory index tree' (duration: 240.081521ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:53.405286Z","caller":"traceutil/trace.go:171","msg":"trace[551277891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"240.516701ms","start":"2026-03-18T00:46:53.164751Z","end":"2026-03-18T00:46:53.405268Z","steps":["trace[551277891] 'range keys from in-memory index tree' (duration: 240.217594ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:53.405461Z","caller":"traceutil/trace.go:171","msg":"trace[108924362] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"173.567061ms","start":"2026-03-18T00:46:53.231873Z","end":"2026-03-18T00:46:53.405441Z","steps":["trace[108924362] 'read index received' (duration: 173.386967ms)","trace[108924362] 'applied index is now lower than readState.Index' (duration: 179.254µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:46:53.405576Z","caller":"traceutil/trace.go:171","msg":"trace[112911779] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"209.392707ms","start":"2026-03-18T00:46:53.196147Z","end":"2026-03-18T00:46:53.405539Z","steps":["trace[112911779] 'process raft request' (duration: 208.940707ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:53.40574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.772756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-18T00:46:53.405833Z","caller":"traceutil/trace.go:171","msg":"trace[1091302258] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:561; }","duration":"173.98187ms","start":"2026-03-18T00:46:53.231838Z","end":"2026-03-18T00:46:53.40582Z","steps":["trace[1091302258] 'agreement among raft nodes before linearized reading' (duration: 173.719214ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:57.500993Z","caller":"traceutil/trace.go:171","msg":"trace[472764430] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"157.348268ms","start":"2026-03-18T00:46:57.343587Z","end":"2026-03-18T00:46:57.500935Z","steps":["trace[472764430] 'process raft request' (duration: 157.212665ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:59.459966Z","caller":"traceutil/trace.go:171","msg":"trace[171579860] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"105.994915ms","start":"2026-03-18T00:46:59.353954Z","end":"2026-03-18T00:46:59.459949Z","steps":["trace[171579860] 'process raft request' (duration: 105.651767ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:01.556254Z","caller":"traceutil/trace.go:171","msg":"trace[814364494] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"459.268726ms","start":"2026-03-18T00:47:01.09697Z","end":"2026-03-18T00:47:01.556238Z","steps":["trace[814364494] 'read index received' (duration: 459.187454ms)","trace[814364494] 'applied index is now lower than readState.Index' (duration: 80.542µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:47:01.556314Z","caller":"traceutil/trace.go:171","msg":"trace[1264284458] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"467.746541ms","start":"2026-03-18T00:47:01.088537Z","end":"2026-03-18T00:47:01.556283Z","steps":["trace[1264284458] 'process raft request' (duration: 467.604357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:01.556403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.430289ms","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-03-18T00:47:01.55643Z","caller":"traceutil/trace.go:171","msg":"trace[362636164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:656; }","duration":"459.501211ms","start":"2026-03-18T00:47:01.09692Z","end":"2026-03-18T00:47:01.556421Z","steps":["trace[362636164] 'agreement among raft nodes before linearized reading' (duration: 459.415759ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:01.55645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:01.0969Z","time spent":"459.545343ms","remote":"127.0.0.1:54090","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-03-18T00:47:01.556463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:01.088516Z","time spent":"467.871734ms","remote":"127.0.0.1:54090","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-03-18T00:47:02.725244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.489099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T00:47:02.725345Z","caller":"traceutil/trace.go:171","msg":"trace[40574891] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"1.164265858s","start":"2026-03-18T00:47:01.561062Z","end":"2026-03-18T00:47:02.725328Z","steps":["trace[40574891] 'process raft request' (duration: 345.610095ms)","trace[40574891] 'compare' (duration: 818.354346ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:47:02.725402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:01.561051Z","time spent":"1.164325039s","remote":"127.0.0.1:54090","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-03-18T00:47:02.745292Z","caller":"traceutil/trace.go:171","msg":"trace[204623587] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"1.184162599s","start":"2026-03-18T00:47:01.561119Z","end":"2026-03-18T00:47:02.745282Z","steps":["trace[204623587] 'process raft request' (duration: 1.184066417s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:02.745351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:01.561107Z","time spent":"1.1842168s","remote":"127.0.0.1:54090","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-03-18T00:47:02.74542Z","caller":"traceutil/trace.go:171","msg":"trace[1641116792] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:675; }","duration":"857.345449ms","start":"2026-03-18T00:47:01.888067Z","end":"2026-03-18T00:47:02.745413Z","steps":["trace[1641116792] 'read index received' (duration: 18.614271ms)","trace[1641116792] 'applied index is now lower than readState.Index' (duration: 838.730498ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:47:02.745485Z","caller":"traceutil/trace.go:171","msg":"trace[69236054] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.183890943s","start":"2026-03-18T00:47:01.561589Z","end":"2026-03-18T00:47:02.74548Z","steps":["trace[69236054] 'process raft request' (duration: 1.183668328s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:02.745514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:01.561584Z","time spent":"1.183913513s","remote":"127.0.0.1:54090","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-03-18T00:47:02.745569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.511663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:47:02.745586Z","caller":"traceutil/trace.go:171","msg":"trace[838126225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"857.564274ms","start":"2026-03-18T00:47:01.888015Z","end":"2026-03-18T00:47:02.745579Z","steps":["trace[838126225] 'agreement among raft nodes before linearized reading' (duration: 857.537774ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:02.745602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:01.887996Z","time spent":"857.602686ms","remote":"127.0.0.1:53836","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-03-18T00:47:02.773778Z","caller":"traceutil/trace.go:171","msg":"trace[1615739522] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"791.350792ms","start":"2026-03-18T00:47:01.982404Z","end":"2026-03-18T00:47:02.773755Z","steps":["trace[1615739522] 'process raft request' (duration: 791.217189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:02.773864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:01.982378Z","time spent":"791.445334ms","remote":"127.0.0.1:53898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T00:47:02.773941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.238404ms","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-03-18T00:47:02.774015Z","caller":"traceutil/trace.go:171","msg":"trace[773159646] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:660; }","duration":"221.365867ms","start":"2026-03-18T00:47:02.552634Z","end":"2026-03-18T00:47:02.773999Z","steps":["trace[773159646] 'agreement among raft nodes before linearized reading' (duration: 221.150742ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:02.774183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.893515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-s5xxv\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-03-18T00:47:02.774207Z","caller":"traceutil/trace.go:171","msg":"trace[114566477] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-s5xxv; range_end:; response_count:1; response_revision:660; }","duration":"792.031918ms","start":"2026-03-18T00:47:01.982169Z","end":"2026-03-18T00:47:02.774201Z","steps":["trace[114566477] 'agreement among raft nodes before linearized reading' (duration: 791.952456ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:02.77422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:01.982153Z","time spent":"792.063409ms","remote":"127.0.0.1:53982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4907,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-s5xxv\" "} {"level":"warn","ts":"2026-03-18T00:47:08.181727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.265898ms","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-03-18T00:47:08.181766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.04759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:47:08.181799Z","caller":"traceutil/trace.go:171","msg":"trace[1394259256] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:680; }","duration":"607.38133ms","start":"2026-03-18T00:47:07.574401Z","end":"2026-03-18T00:47:08.181782Z","steps":["trace[1394259256] 'range keys from in-memory index tree' (duration: 607.054542ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:08.181841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:07.574382Z","time spent":"607.449732ms","remote":"127.0.0.1:54090","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-03-18T00:47:08.181835Z","caller":"traceutil/trace.go:171","msg":"trace[2031610784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"295.153733ms","start":"2026-03-18T00:47:07.886663Z","end":"2026-03-18T00:47:08.181817Z","steps":["trace[2031610784] 'range keys from in-memory index tree' (duration: 294.975659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:09.247719Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113069157282711,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T00:47:09.718362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.407559ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-18T00:47:09.718478Z","caller":"traceutil/trace.go:171","msg":"trace[1738580928] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"971.420852ms","start":"2026-03-18T00:47:08.747039Z","end":"2026-03-18T00:47:09.718459Z","steps":["trace[1738580928] 'read index received' (duration: 737.855489ms)","trace[1738580928] 'applied index is now lower than readState.Index' (duration: 233.564033ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:47:09.718704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.571415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-18T00:47:09.718693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.641107ms","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-03-18T00:47:09.718735Z","caller":"traceutil/trace.go:171","msg":"trace[1269165659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:681; }","duration":"883.626286ms","start":"2026-03-18T00:47:08.835097Z","end":"2026-03-18T00:47:09.718723Z","steps":["trace[1269165659] 'agreement among raft nodes before linearized reading' (duration: 883.478023ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:09.718772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:08.835088Z","time spent":"883.676367ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-18T00:47:09.718774Z","caller":"traceutil/trace.go:171","msg":"trace[198829829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:681; }","duration":"971.75937ms","start":"2026-03-18T00:47:08.746994Z","end":"2026-03-18T00:47:09.718753Z","steps":["trace[198829829] 'agreement among raft nodes before linearized reading' (duration: 971.541955ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:09.718823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:08.746977Z","time spent":"971.836322ms","remote":"127.0.0.1:54090","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-03-18T00:47:09.718995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.486297ms","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-03-18T00:47:09.719026Z","caller":"traceutil/trace.go:171","msg":"trace[1421142871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:681; }","duration":"741.538717ms","start":"2026-03-18T00:47:08.977478Z","end":"2026-03-18T00:47:09.719017Z","steps":["trace[1421142871] 'agreement among raft nodes before linearized reading' (duration: 741.446665ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:09.719054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:08.977467Z","time spent":"741.580649ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T00:47:09.719064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.789938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:47:09.719093Z","caller":"traceutil/trace.go:171","msg":"trace[322155956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"830.857339ms","start":"2026-03-18T00:47:08.888227Z","end":"2026-03-18T00:47:09.719085Z","steps":["trace[322155956] 'agreement among raft nodes before linearized reading' (duration: 830.808588ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:09.719119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:08.888206Z","time spent":"830.907121ms","remote":"127.0.0.1:53836","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-03-18T00:47:09.71928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.310559ms","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-03-18T00:47:09.719304Z","caller":"traceutil/trace.go:171","msg":"trace[1285092222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:681; }","duration":"883.34795ms","start":"2026-03-18T00:47:08.835949Z","end":"2026-03-18T00:47:09.719297Z","steps":["trace[1285092222] 'agreement among raft nodes before linearized reading' (duration: 883.273948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:09.719328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:08.835943Z","time spent":"883.379371ms","remote":"127.0.0.1:54090","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-03-18T00:47:09.832721Z","caller":"traceutil/trace.go:171","msg":"trace[2013516665] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"109.138621ms","start":"2026-03-18T00:47:09.723559Z","end":"2026-03-18T00:47:09.832697Z","steps":["trace[2013516665] 'process raft request' (duration: 109.015368ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:09.84918Z","caller":"traceutil/trace.go:171","msg":"trace[2055905231] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"124.686817ms","start":"2026-03-18T00:47:09.724471Z","end":"2026-03-18T00:47:09.849158Z","steps":["trace[2055905231] 'process raft request' (duration: 124.649496ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:09.849228Z","caller":"traceutil/trace.go:171","msg":"trace[1281664540] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"124.874561ms","start":"2026-03-18T00:47:09.724332Z","end":"2026-03-18T00:47:09.849206Z","steps":["trace[1281664540] 'process raft request' (duration: 124.751798ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:09.849192Z","caller":"traceutil/trace.go:171","msg":"trace[1058622175] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"125.558628ms","start":"2026-03-18T00:47:09.723613Z","end":"2026-03-18T00:47:09.849171Z","steps":["trace[1058622175] 'process raft request' (duration: 125.320162ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:13.976647Z","caller":"traceutil/trace.go:171","msg":"trace[1783962580] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:720; }","duration":"109.720315ms","start":"2026-03-18T00:47:13.866908Z","end":"2026-03-18T00:47:13.976628Z","steps":["trace[1783962580] 'read index received' (duration: 109.646793ms)","trace[1783962580] 'applied index is now lower than readState.Index' (duration: 72.792µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:47:13.976669Z","caller":"traceutil/trace.go:171","msg":"trace[922216323] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"111.574851ms","start":"2026-03-18T00:47:13.865078Z","end":"2026-03-18T00:47:13.976653Z","steps":["trace[922216323] 'process raft request' (duration: 111.440097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:13.976833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.90084ms","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-03-18T00:47:13.976859Z","caller":"traceutil/trace.go:171","msg":"trace[2030088364] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:699; }","duration":"109.962342ms","start":"2026-03-18T00:47:13.866888Z","end":"2026-03-18T00:47:13.976851Z","steps":["trace[2030088364] 'agreement among raft nodes before linearized reading' (duration: 109.836988ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:13.991806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.815441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-18T00:47:13.991819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.498975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:47:13.991866Z","caller":"traceutil/trace.go:171","msg":"trace[1256735570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:700; }","duration":"124.914954ms","start":"2026-03-18T00:47:13.866938Z","end":"2026-03-18T00:47:13.991853Z","steps":["trace[1256735570] 'agreement among raft nodes before linearized reading' (duration: 124.714999ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:13.991916Z","caller":"traceutil/trace.go:171","msg":"trace[1406139076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"103.642899ms","start":"2026-03-18T00:47:13.888252Z","end":"2026-03-18T00:47:13.991895Z","steps":["trace[1406139076] 'agreement among raft nodes before linearized reading' (duration: 103.467584ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:24.221156Z","caller":"traceutil/trace.go:171","msg":"trace[147983052] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"108.845515ms","start":"2026-03-18T00:47:24.112286Z","end":"2026-03-18T00:47:24.221131Z","steps":["trace[147983052] 'process raft request' (duration: 108.687801ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:24.251493Z","caller":"traceutil/trace.go:171","msg":"trace[1573004396] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"138.732707ms","start":"2026-03-18T00:47:24.112726Z","end":"2026-03-18T00:47:24.251459Z","steps":["trace[1573004396] 'process raft request' (duration: 130.478493ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:24.251783Z","caller":"traceutil/trace.go:171","msg":"trace[1461527530] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"135.607393ms","start":"2026-03-18T00:47:24.116144Z","end":"2026-03-18T00:47:24.251751Z","steps":["trace[1461527530] 'process raft request' (duration: 135.265755ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:32.108222Z","caller":"traceutil/trace.go:171","msg":"trace[1380736536] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"160.461669ms","start":"2026-03-18T00:47:31.947741Z","end":"2026-03-18T00:47:32.108203Z","steps":["trace[1380736536] 'process raft request' (duration: 160.355366ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:32.591354Z","caller":"traceutil/trace.go:171","msg":"trace[1197583807] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"203.090578ms","start":"2026-03-18T00:47:32.388156Z","end":"2026-03-18T00:47:32.591247Z","steps":["trace[1197583807] 'process raft request' (duration: 135.320847ms)","trace[1197583807] 'compare' (duration: 67.621138ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:47:34.165054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.60404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T00:47:34.165181Z","caller":"traceutil/trace.go:171","msg":"trace[131472675] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:779; }","duration":"276.993664ms","start":"2026-03-18T00:47:33.888154Z","end":"2026-03-18T00:47:34.165148Z","steps":["trace[131472675] 'read index received' (duration: 125.124838ms)","trace[131472675] 'applied index is now lower than readState.Index' (duration: 151.867246ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:47:34.165293Z","caller":"traceutil/trace.go:171","msg":"trace[261343259] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"651.710143ms","start":"2026-03-18T00:47:33.513567Z","end":"2026-03-18T00:47:34.165277Z","steps":["trace[261343259] 'process raft request' (duration: 499.790026ms)","trace[261343259] 'compare' (duration: 151.437986ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:47:34.165382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:33.513542Z","time spent":"651.792215ms","remote":"127.0.0.1:53850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:47:34.165665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.512506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:47:34.165712Z","caller":"traceutil/trace.go:171","msg":"trace[910207884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:754; }","duration":"277.596688ms","start":"2026-03-18T00:47:33.888102Z","end":"2026-03-18T00:47:34.165698Z","steps":["trace[910207884] 'agreement among raft nodes before linearized reading' (duration: 277.487436ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:34.528852Z","caller":"traceutil/trace.go:171","msg":"trace[1964822583] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"141.84536ms","start":"2026-03-18T00:47:34.386983Z","end":"2026-03-18T00:47:34.528829Z","steps":["trace[1964822583] 'process raft request' (duration: 141.783309ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:34.528866Z","caller":"traceutil/trace.go:171","msg":"trace[1208075827] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"358.518849ms","start":"2026-03-18T00:47:34.170318Z","end":"2026-03-18T00:47:34.528837Z","steps":["trace[1208075827] 'process raft request' (duration: 292.209001ms)","trace[1208075827] 'compare' (duration: 66.088872ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:47:34.529142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.235842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-03-18T00:47:34.529173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:34.170305Z","time spent":"358.791225ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:47:34.529303Z","caller":"traceutil/trace.go:171","msg":"trace[847893886] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:756; }","duration":"358.374345ms","start":"2026-03-18T00:47:34.170865Z","end":"2026-03-18T00:47:34.529239Z","steps":["trace[847893886] 'agreement among raft nodes before linearized reading' (duration: 358.120069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:34.529381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:34.170859Z","time spent":"358.505718ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-03-18T00:47:34.52895Z","caller":"traceutil/trace.go:171","msg":"trace[824237874] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:780; }","duration":"358.040838ms","start":"2026-03-18T00:47:34.170885Z","end":"2026-03-18T00:47:34.528926Z","steps":["trace[824237874] 'read index received' (duration: 291.655189ms)","trace[824237874] 'applied index is now lower than readState.Index' (duration: 66.382919ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:47:34.530307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.699336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-18T00:47:34.530472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.817771ms","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-03-18T00:47:34.530502Z","caller":"traceutil/trace.go:171","msg":"trace[1820572373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:756; }","duration":"241.784167ms","start":"2026-03-18T00:47:34.288566Z","end":"2026-03-18T00:47:34.53035Z","steps":["trace[1820572373] 'agreement among raft nodes before linearized reading' (duration: 241.506181ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:34.530538Z","caller":"traceutil/trace.go:171","msg":"trace[334617077] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:756; }","duration":"144.925973ms","start":"2026-03-18T00:47:34.385595Z","end":"2026-03-18T00:47:34.530521Z","steps":["trace[334617077] 'agreement among raft nodes before linearized reading' (duration: 144.762669ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:34.530821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.127668ms","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-03-18T00:47:34.531002Z","caller":"traceutil/trace.go:171","msg":"trace[2143334698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"145.357823ms","start":"2026-03-18T00:47:34.38563Z","end":"2026-03-18T00:47:34.530987Z","steps":["trace[2143334698] 'agreement among raft nodes before linearized reading' (duration: 144.648167ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:34.703727Z","caller":"traceutil/trace.go:171","msg":"trace[2045709434] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:782; }","duration":"134.280013ms","start":"2026-03-18T00:47:34.569427Z","end":"2026-03-18T00:47:34.703707Z","steps":["trace[2045709434] 'read index received' (duration: 60.122752ms)","trace[2045709434] 'applied index is now lower than readState.Index' (duration: 74.156501ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:47:34.703791Z","caller":"traceutil/trace.go:171","msg":"trace[522779671] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"169.041479ms","start":"2026-03-18T00:47:34.534729Z","end":"2026-03-18T00:47:34.70377Z","steps":["trace[522779671] 'process raft request' (duration: 168.912206ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:34.703779Z","caller":"traceutil/trace.go:171","msg":"trace[901548373] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"169.132122ms","start":"2026-03-18T00:47:34.534609Z","end":"2026-03-18T00:47:34.703742Z","steps":["trace[901548373] 'process raft request' (duration: 94.93013ms)","trace[901548373] 'compare' (duration: 73.989577ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:47:34.703918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.452677ms","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-03-18T00:47:34.703965Z","caller":"traceutil/trace.go:171","msg":"trace[1276221865] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:759; }","duration":"134.5717ms","start":"2026-03-18T00:47:34.569382Z","end":"2026-03-18T00:47:34.703954Z","steps":["trace[1276221865] 'agreement among raft nodes before linearized reading' (duration: 134.447627ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:34.703913Z","caller":"traceutil/trace.go:171","msg":"trace[1128338771] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"168.088037ms","start":"2026-03-18T00:47:34.535805Z","end":"2026-03-18T00:47:34.703894Z","steps":["trace[1128338771] 'process raft request' (duration: 167.873312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:34.704117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.408706ms","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-03-18T00:47:34.704175Z","caller":"traceutil/trace.go:171","msg":"trace[1254965905] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:759; }","duration":"108.509077ms","start":"2026-03-18T00:47:34.595651Z","end":"2026-03-18T00:47:34.70416Z","steps":["trace[1254965905] 'agreement among raft nodes before linearized reading' (duration: 108.331103ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:36.262895Z","caller":"traceutil/trace.go:171","msg":"trace[847070442] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"146.742256ms","start":"2026-03-18T00:47:36.116125Z","end":"2026-03-18T00:47:36.262867Z","steps":["trace[847070442] 'process raft request' (duration: 146.580122ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:36.639541Z","caller":"traceutil/trace.go:171","msg":"trace[892191831] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"102.490366ms","start":"2026-03-18T00:47:36.537027Z","end":"2026-03-18T00:47:36.639517Z","steps":["trace[892191831] 'process raft request' (duration: 102.246571ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:37.18945Z","caller":"traceutil/trace.go:171","msg":"trace[1675711280] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"453.471728ms","start":"2026-03-18T00:47:36.73594Z","end":"2026-03-18T00:47:37.189412Z","steps":["trace[1675711280] 'process raft request' (duration: 453.345495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:37.189596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:36.735917Z","time spent":"453.604182ms","remote":"127.0.0.1:54090","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-03-18T00:47:37.325287Z","caller":"traceutil/trace.go:171","msg":"trace[1025221715] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"589.267197ms","start":"2026-03-18T00:47:36.735997Z","end":"2026-03-18T00:47:37.325264Z","steps":["trace[1025221715] 'process raft request' (duration: 579.302153ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:37.32533Z","caller":"traceutil/trace.go:171","msg":"trace[1118459279] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"588.838928ms","start":"2026-03-18T00:47:36.736475Z","end":"2026-03-18T00:47:37.325314Z","steps":["trace[1118459279] 'process raft request' (duration: 588.675994ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:37.325427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:36.736464Z","time spent":"588.909039ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:47:37.325508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:36.735976Z","time spent":"589.38334ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T00:47:37.32528Z","caller":"traceutil/trace.go:171","msg":"trace[543130709] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:789; }","duration":"583.670006ms","start":"2026-03-18T00:47:36.741585Z","end":"2026-03-18T00:47:37.325255Z","steps":["trace[543130709] 'read index received' (duration: 447.860337ms)","trace[543130709] 'applied index is now lower than readState.Index' (duration: 135.808289ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:47:37.32609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.85816ms","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-03-18T00:47:37.326173Z","caller":"traceutil/trace.go:171","msg":"trace[1122943629] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:765; }","duration":"584.578627ms","start":"2026-03-18T00:47:36.741562Z","end":"2026-03-18T00:47:37.326141Z","steps":["trace[1122943629] 'agreement among raft nodes before linearized reading' (duration: 583.767398ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:37.326231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:36.741554Z","time spent":"584.662349ms","remote":"127.0.0.1:54090","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-03-18T00:47:41.498166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.803083ms","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-03-18T00:47:41.498191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.665693ms","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-03-18T00:47:41.498298Z","caller":"traceutil/trace.go:171","msg":"trace[2044537230] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:773; }","duration":"140.815937ms","start":"2026-03-18T00:47:41.357461Z","end":"2026-03-18T00:47:41.498277Z","steps":["trace[2044537230] 'range keys from in-memory index tree' (duration: 140.497489ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:41.498316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.723545ms","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-03-18T00:47:41.498218Z","caller":"traceutil/trace.go:171","msg":"trace[718330806] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:773; }","duration":"139.880075ms","start":"2026-03-18T00:47:41.358326Z","end":"2026-03-18T00:47:41.498206Z","steps":["trace[718330806] 'range keys from in-memory index tree' (duration: 139.67758ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:41.498398Z","caller":"traceutil/trace.go:171","msg":"trace[824204539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:773; }","duration":"140.806337ms","start":"2026-03-18T00:47:41.357554Z","end":"2026-03-18T00:47:41.49836Z","steps":["trace[824204539] 'range keys from in-memory index tree' (duration: 140.569281ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:41.498143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.635284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T00:47:41.498511Z","caller":"traceutil/trace.go:171","msg":"trace[1245699033] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:773; }","duration":"107.022984ms","start":"2026-03-18T00:47:41.391451Z","end":"2026-03-18T00:47:41.498474Z","steps":["trace[1245699033] 'count revisions from in-memory index tree' (duration: 106.587623ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:41.645038Z","caller":"traceutil/trace.go:171","msg":"trace[61611202] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"142.663759ms","start":"2026-03-18T00:47:41.502351Z","end":"2026-03-18T00:47:41.645015Z","steps":["trace[61611202] 'process raft request' (duration: 142.525226ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:41.847893Z","caller":"traceutil/trace.go:171","msg":"trace[505613983] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"345.086753ms","start":"2026-03-18T00:47:41.502784Z","end":"2026-03-18T00:47:41.847871Z","steps":["trace[505613983] 'process raft request' (duration: 344.886748ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:41.848001Z","caller":"traceutil/trace.go:171","msg":"trace[1375012335] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:801; }","duration":"159.391992ms","start":"2026-03-18T00:47:41.688583Z","end":"2026-03-18T00:47:41.847975Z","steps":["trace[1375012335] 'read index received' (duration: 159.064354ms)","trace[1375012335] 'applied index is now lower than readState.Index' (duration: 326.458µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:47:41.84808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:41.502768Z","time spent":"345.216586ms","remote":"127.0.0.1:54090","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-03-18T00:47:41.848109Z","caller":"traceutil/trace.go:171","msg":"trace[1403458854] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"344.795296ms","start":"2026-03-18T00:47:41.503298Z","end":"2026-03-18T00:47:41.848093Z","steps":["trace[1403458854] 'process raft request' (duration: 344.52709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:41.848148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.539917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:47:41.848208Z","caller":"traceutil/trace.go:171","msg":"trace[1965433539] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:776; }","duration":"159.652869ms","start":"2026-03-18T00:47:41.688538Z","end":"2026-03-18T00:47:41.848191Z","steps":["trace[1965433539] 'agreement among raft nodes before linearized reading' (duration: 159.545877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:41.848208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:41.503289Z","time spent":"344.858517ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:47:50.132198Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113069157283132,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T00:47:50.633813Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113069157283132,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-18T00:47:50.826354Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.55479616s","expected-duration":"1s"} {"level":"info","ts":"2026-03-18T00:47:50.826698Z","caller":"traceutil/trace.go:171","msg":"trace[1472948017] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:878; }","duration":"1.194912179s","start":"2026-03-18T00:47:49.631763Z","end":"2026-03-18T00:47:50.826675Z","steps":["trace[1472948017] 'read index received' (duration: 1.194756845s)","trace[1472948017] 'applied index is now lower than readState.Index' (duration: 154.334µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:47:50.826819Z","caller":"traceutil/trace.go:171","msg":"trace[1669902196] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"1.555315222s","start":"2026-03-18T00:47:49.271495Z","end":"2026-03-18T00:47:50.82681Z","steps":["trace[1669902196] 'process raft request' (duration: 1.555040106s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:50.826919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:49.271484Z","time spent":"1.555367853s","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:47:50.827129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153679821s","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-03-18T00:47:50.82719Z","caller":"traceutil/trace.go:171","msg":"trace[748834918] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:848; }","duration":"1.153776883s","start":"2026-03-18T00:47:49.673396Z","end":"2026-03-18T00:47:50.827173Z","steps":["trace[748834918] 'agreement among raft nodes before linearized reading' (duration: 1.15361692s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:50.827237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:49.67338Z","time spent":"1.153849395s","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T00:47:50.82727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.412005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:47:50.827321Z","caller":"traceutil/trace.go:171","msg":"trace[1620740738] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:848; }","duration":"524.495386ms","start":"2026-03-18T00:47:50.302812Z","end":"2026-03-18T00:47:50.827307Z","steps":["trace[1620740738] 'agreement among raft nodes before linearized reading' (duration: 524.423084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:50.827358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:50.302793Z","time spent":"524.557358ms","remote":"127.0.0.1:54132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-18T00:47:50.827507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195750709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-18T00:47:50.827511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.430102ms","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-03-18T00:47:50.827543Z","caller":"traceutil/trace.go:171","msg":"trace[442154139] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:848; }","duration":"1.19582356s","start":"2026-03-18T00:47:49.631711Z","end":"2026-03-18T00:47:50.827535Z","steps":["trace[442154139] 'agreement among raft nodes before linearized reading' (duration: 1.195769009s)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:50.82756Z","caller":"traceutil/trace.go:171","msg":"trace[164009647] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:848; }","duration":"934.514984ms","start":"2026-03-18T00:47:49.893028Z","end":"2026-03-18T00:47:50.827543Z","steps":["trace[164009647] 'agreement among raft nodes before linearized reading' (duration: 934.383261ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:50.82761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:49.893009Z","time spent":"934.592487ms","remote":"127.0.0.1:54090","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-03-18T00:47:50.827577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:49.631691Z","time spent":"1.195879502s","remote":"127.0.0.1:54230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-18T00:47:50.827844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.929201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:47:50.828041Z","caller":"traceutil/trace.go:171","msg":"trace[890430791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:848; }","duration":"940.108756ms","start":"2026-03-18T00:47:49.887799Z","end":"2026-03-18T00:47:50.827908Z","steps":["trace[890430791] 'agreement among raft nodes before linearized reading' (duration: 939.907031ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:50.828075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.678022ms","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-03-18T00:47:50.828123Z","caller":"traceutil/trace.go:171","msg":"trace[1708520089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:848; }","duration":"938.762875ms","start":"2026-03-18T00:47:49.889351Z","end":"2026-03-18T00:47:50.828114Z","steps":["trace[1708520089] 'agreement among raft nodes before linearized reading' (duration: 938.660152ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:50.828155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:49.889332Z","time spent":"938.817375ms","remote":"127.0.0.1:54090","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-03-18T00:47:50.828253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:49.887779Z","time spent":"940.460785ms","remote":"127.0.0.1:53836","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-03-18T00:47:51.499422Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113069157283136,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-18T00:47:51.880757Z","caller":"traceutil/trace.go:171","msg":"trace[450396001] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"1.049033643s","start":"2026-03-18T00:47:50.831701Z","end":"2026-03-18T00:47:51.880735Z","steps":["trace[450396001] 'process raft request' (duration: 1.048849859s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:51.881006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:50.831685Z","time spent":"1.049250919s","remote":"127.0.0.1:54090","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-03-18T00:47:51.890895Z","caller":"traceutil/trace.go:171","msg":"trace[1772068576] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"1.059180731s","start":"2026-03-18T00:47:50.831704Z","end":"2026-03-18T00:47:51.890885Z","steps":["trace[1772068576] 'process raft request' (duration: 1.059096399s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:51.890983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:50.831679Z","time spent":"1.059248503s","remote":"127.0.0.1:54090","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-03-18T00:47:51.891145Z","caller":"traceutil/trace.go:171","msg":"trace[1670797968] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"1.059371926s","start":"2026-03-18T00:47:50.831749Z","end":"2026-03-18T00:47:51.891121Z","steps":["trace[1670797968] 'process raft request' (duration: 1.05910956s)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:51.891212Z","caller":"traceutil/trace.go:171","msg":"trace[1563334952] linearizableReadLoop","detail":"{readStateIndex:883; appliedIndex:879; }","duration":"892.136479ms","start":"2026-03-18T00:47:50.999067Z","end":"2026-03-18T00:47:51.891204Z","steps":["trace[1563334952] 'read index received' (duration: 881.493679ms)","trace[1563334952] 'applied index is now lower than readState.Index' (duration: 10.64226ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:47:51.891327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:50.831728Z","time spent":"1.059485839s","remote":"127.0.0.1:54090","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-03-18T00:47:51.89136Z","caller":"traceutil/trace.go:171","msg":"trace[1743541477] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"1.052529645s","start":"2026-03-18T00:47:50.838823Z","end":"2026-03-18T00:47:51.891353Z","steps":["trace[1743541477] 'process raft request' (duration: 1.052231358s)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:51.89142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:50.838801Z","time spent":"1.052577226s","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:47:51.891593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.513318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:47:51.89165Z","caller":"traceutil/trace.go:171","msg":"trace[951599798] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:852; }","duration":"892.60197ms","start":"2026-03-18T00:47:50.999034Z","end":"2026-03-18T00:47:51.891636Z","steps":["trace[951599798] 'agreement among raft nodes before linearized reading' (duration: 892.516968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:51.891692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:47:50.999017Z","time spent":"892.665891ms","remote":"127.0.0.1:53928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-03-18T00:47:53.106518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.762188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:47:53.10659Z","caller":"traceutil/trace.go:171","msg":"trace[334581071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:853; }","duration":"218.864079ms","start":"2026-03-18T00:47:52.887708Z","end":"2026-03-18T00:47:53.106572Z","steps":["trace[334581071] 'range keys from in-memory index tree' (duration: 218.664965ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:07.523249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.480884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:48:07.523349Z","caller":"traceutil/trace.go:171","msg":"trace[1583304414] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:894; }","duration":"357.664209ms","start":"2026-03-18T00:48:07.165668Z","end":"2026-03-18T00:48:07.523332Z","steps":["trace[1583304414] 'count revisions from in-memory index tree' (duration: 357.352281ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:07.523393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:07.165635Z","time spent":"357.74851ms","remote":"127.0.0.1:54116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-03-18T00:48:08.172144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.669203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-18T00:48:08.172248Z","caller":"traceutil/trace.go:171","msg":"trace[50482134] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:895; }","duration":"120.813207ms","start":"2026-03-18T00:48:08.051411Z","end":"2026-03-18T00:48:08.172224Z","steps":["trace[50482134] 'range keys from in-memory index tree' (duration: 120.435108ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:13.871349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.079014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T00:48:13.871464Z","caller":"traceutil/trace.go:171","msg":"trace[259925253] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"429.511036ms","start":"2026-03-18T00:48:13.441935Z","end":"2026-03-18T00:48:13.871446Z","steps":["trace[259925253] 'process raft request' (duration: 279.263628ms)","trace[259925253] 'compare' (duration: 149.966381ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:13.871543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:13.44191Z","time spent":"429.604967ms","remote":"127.0.0.1:53850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:48:14.96468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.982636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-18T00:48:14.965Z","caller":"traceutil/trace.go:171","msg":"trace[1992007795] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"485.334956ms","start":"2026-03-18T00:48:14.479617Z","end":"2026-03-18T00:48:14.964952Z","steps":["trace[1992007795] 'process raft request' (duration: 278.979951ms)","trace[1992007795] 'compare' (duration: 205.823583ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:14.965152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:14.479595Z","time spent":"485.48827ms","remote":"127.0.0.1:53898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":842,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T00:48:14.96534Z","caller":"traceutil/trace.go:171","msg":"trace[434167459] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"484.63841ms","start":"2026-03-18T00:48:14.480676Z","end":"2026-03-18T00:48:14.965315Z","steps":["trace[434167459] 'process raft request' (duration: 484.135408ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:14.965446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:14.48066Z","time spent":"484.744033ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-18T00:48:15.467993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.923096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-18T00:48:15.468095Z","caller":"traceutil/trace.go:171","msg":"trace[1209678] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"495.927376ms","start":"2026-03-18T00:48:14.972149Z","end":"2026-03-18T00:48:15.468077Z","steps":["trace[1209678] 'process raft request' (duration: 289.804936ms)","trace[1209678] 'compare' (duration: 205.520386ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:15.468147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:14.972124Z","time spent":"496.001567ms","remote":"127.0.0.1:53898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":868,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-18T00:48:15.566335Z","caller":"traceutil/trace.go:171","msg":"trace[1896327950] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:982; }","duration":"438.342993ms","start":"2026-03-18T00:48:15.127975Z","end":"2026-03-18T00:48:15.566318Z","steps":["trace[1896327950] 'read index received' (duration: 133.996306ms)","trace[1896327950] 'applied index is now lower than readState.Index' (duration: 304.345857ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:15.566504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.514947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-18T00:48:15.56648Z","caller":"traceutil/trace.go:171","msg":"trace[1673484449] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"591.352776ms","start":"2026-03-18T00:48:14.975074Z","end":"2026-03-18T00:48:15.566427Z","steps":["trace[1673484449] 'process raft request' (duration: 591.043549ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:15.566541Z","caller":"traceutil/trace.go:171","msg":"trace[254659849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:949; }","duration":"438.588089ms","start":"2026-03-18T00:48:15.127943Z","end":"2026-03-18T00:48:15.566531Z","steps":["trace[254659849] 'agreement among raft nodes before linearized reading' (duration: 438.444035ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:15.566572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:15.127931Z","time spent":"438.63497ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-18T00:48:15.566577Z","caller":"traceutil/trace.go:171","msg":"trace[1021413369] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"515.330691ms","start":"2026-03-18T00:48:15.051173Z","end":"2026-03-18T00:48:15.566504Z","steps":["trace[1021413369] 'process raft request' (duration: 515.104366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:15.566688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:14.975063Z","time spent":"591.50393ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:48:15.566835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:15.051154Z","time spent":"515.621627ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:48:16.733923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.510187ms","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-03-18T00:48:16.734008Z","caller":"traceutil/trace.go:171","msg":"trace[122157604] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:951; }","duration":"415.657581ms","start":"2026-03-18T00:48:16.318329Z","end":"2026-03-18T00:48:16.733987Z","steps":["trace[122157604] 'range keys from in-memory index tree' (duration: 415.292582ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:16.734052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:16.31831Z","time spent":"415.733583ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T00:48:16.734104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.194992ms","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-03-18T00:48:16.734183Z","caller":"traceutil/trace.go:171","msg":"trace[1587703885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:951; }","duration":"381.321855ms","start":"2026-03-18T00:48:16.352842Z","end":"2026-03-18T00:48:16.734164Z","steps":["trace[1587703885] 'range keys from in-memory index tree' (duration: 381.020097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:16.734229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:16.352832Z","time spent":"381.388546ms","remote":"127.0.0.1:54090","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-03-18T00:48:16.734437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.520279ms","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-03-18T00:48:16.734476Z","caller":"traceutil/trace.go:171","msg":"trace[1492227964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:951; }","duration":"381.582601ms","start":"2026-03-18T00:48:16.352883Z","end":"2026-03-18T00:48:16.734466Z","steps":["trace[1492227964] 'range keys from in-memory index tree' (duration: 381.393346ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:16.734473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.500883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T00:48:16.734505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:16.35287Z","time spent":"381.629282ms","remote":"127.0.0.1:54090","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-03-18T00:48:16.734528Z","caller":"traceutil/trace.go:171","msg":"trace[182857094] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:951; }","duration":"474.601695ms","start":"2026-03-18T00:48:16.259914Z","end":"2026-03-18T00:48:16.734516Z","steps":["trace[182857094] 'count revisions from in-memory index tree' (duration: 474.379349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:16.73457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:16.259891Z","time spent":"474.669676ms","remote":"127.0.0.1:54342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-03-18T00:48:16.888302Z","caller":"traceutil/trace.go:171","msg":"trace[1838449223] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"144.978864ms","start":"2026-03-18T00:48:16.743299Z","end":"2026-03-18T00:48:16.888278Z","steps":["trace[1838449223] 'process raft request' (duration: 144.620186ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:16.932711Z","caller":"traceutil/trace.go:171","msg":"trace[1374368573] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"189.032188ms","start":"2026-03-18T00:48:16.743654Z","end":"2026-03-18T00:48:16.932687Z","steps":["trace[1374368573] 'process raft request' (duration: 188.866624ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:16.932727Z","caller":"traceutil/trace.go:171","msg":"trace[1536114915] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"188.856905ms","start":"2026-03-18T00:48:16.743851Z","end":"2026-03-18T00:48:16.932708Z","steps":["trace[1536114915] 'process raft request' (duration: 188.775933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:16.933059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.444814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-18T00:48:16.933153Z","caller":"traceutil/trace.go:171","msg":"trace[825249532] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:954; }","duration":"139.588078ms","start":"2026-03-18T00:48:16.793542Z","end":"2026-03-18T00:48:16.933131Z","steps":["trace[825249532] 'agreement among raft nodes before linearized reading' (duration: 139.27228ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:16.933276Z","caller":"traceutil/trace.go:171","msg":"trace[1296261417] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:988; }","duration":"139.086206ms","start":"2026-03-18T00:48:16.79358Z","end":"2026-03-18T00:48:16.932666Z","steps":["trace[1296261417] 'read index received' (duration: 95.182035ms)","trace[1296261417] 'applied index is now lower than readState.Index' (duration: 43.903251ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:48:18.020359Z","caller":"traceutil/trace.go:171","msg":"trace[2123497322] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"376.492069ms","start":"2026-03-18T00:48:17.643844Z","end":"2026-03-18T00:48:18.020336Z","steps":["trace[2123497322] 'read index received' (duration: 376.283825ms)","trace[2123497322] 'applied index is now lower than readState.Index' (duration: 207.524µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:18.020565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.705415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-18T00:48:18.020606Z","caller":"traceutil/trace.go:171","msg":"trace[1452771912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:957; }","duration":"376.798617ms","start":"2026-03-18T00:48:17.643795Z","end":"2026-03-18T00:48:18.020594Z","steps":["trace[1452771912] 'agreement among raft nodes before linearized reading' (duration: 376.630434ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:18.020559Z","caller":"traceutil/trace.go:171","msg":"trace[278623518] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"529.746089ms","start":"2026-03-18T00:48:17.490776Z","end":"2026-03-18T00:48:18.020522Z","steps":["trace[278623518] 'process raft request' (duration: 529.407061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:18.020641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:17.643774Z","time spent":"376.858508ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T00:48:18.021309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:17.490754Z","time spent":"530.462755ms","remote":"127.0.0.1:53976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6173,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:48:18.25143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.05968ms","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-03-18T00:48:18.251496Z","caller":"traceutil/trace.go:171","msg":"trace[1419310480] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:958; }","duration":"152.142872ms","start":"2026-03-18T00:48:18.09934Z","end":"2026-03-18T00:48:18.251483Z","steps":["trace[1419310480] 'agreement among raft nodes before linearized reading' (duration: 151.911787ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:18.251513Z","caller":"traceutil/trace.go:171","msg":"trace[665625454] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"225.72642ms","start":"2026-03-18T00:48:18.025763Z","end":"2026-03-18T00:48:18.251489Z","steps":["trace[665625454] 'process raft request' (duration: 198.238935ms)","trace[665625454] 'compare' (duration: 27.075636ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:48:18.251217Z","caller":"traceutil/trace.go:171","msg":"trace[1557519144] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"151.838515ms","start":"2026-03-18T00:48:18.099354Z","end":"2026-03-18T00:48:18.251193Z","steps":["trace[1557519144] 'read index received' (duration: 124.600546ms)","trace[1557519144] 'applied index is now lower than readState.Index' (duration: 27.236839ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:18.546511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.224508ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-18T00:48:20.394177Z","caller":"traceutil/trace.go:171","msg":"trace[1733901213] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"120.717755ms","start":"2026-03-18T00:48:20.273432Z","end":"2026-03-18T00:48:20.39415Z","steps":["trace[1733901213] 'read index received' (duration: 120.489089ms)","trace[1733901213] 'applied index is now lower than readState.Index' (duration: 227.536µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:20.39456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.102154ms","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-03-18T00:48:20.394645Z","caller":"traceutil/trace.go:171","msg":"trace[1690616074] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:970; }","duration":"121.236007ms","start":"2026-03-18T00:48:20.273396Z","end":"2026-03-18T00:48:20.394632Z","steps":["trace[1690616074] 'agreement among raft nodes before linearized reading' (duration: 120.970221ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:20.394648Z","caller":"traceutil/trace.go:171","msg":"trace[607504808] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"133.807932ms","start":"2026-03-18T00:48:20.260813Z","end":"2026-03-18T00:48:20.39462Z","steps":["trace[607504808] 'process raft request' (duration: 133.200887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:20.881175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.21958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T00:48:20.881278Z","caller":"traceutil/trace.go:171","msg":"trace[1337133691] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"310.03732ms","start":"2026-03-18T00:48:20.571224Z","end":"2026-03-18T00:48:20.881261Z","steps":["trace[1337133691] 'read index received' (duration: 147.645447ms)","trace[1337133691] 'applied index is now lower than readState.Index' (duration: 162.390773ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:48:20.881338Z","caller":"traceutil/trace.go:171","msg":"trace[893355969] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"482.962481ms","start":"2026-03-18T00:48:20.398345Z","end":"2026-03-18T00:48:20.881307Z","steps":["trace[893355969] 'process raft request' (duration: 320.517067ms)","trace[893355969] 'compare' (duration: 162.092956ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:20.881517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.295106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-18T00:48:20.881557Z","caller":"traceutil/trace.go:171","msg":"trace[482020687] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:971; }","duration":"310.373358ms","start":"2026-03-18T00:48:20.571168Z","end":"2026-03-18T00:48:20.881541Z","steps":["trace[482020687] 'agreement among raft nodes before linearized reading' (duration: 310.226084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:20.881555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:20.398327Z","time spent":"483.081764ms","remote":"127.0.0.1:54090","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-03-18T00:48:20.88159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:48:20.571147Z","time spent":"310.43521ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-18T00:48:21.168665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.867562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T00:48:21.168772Z","caller":"traceutil/trace.go:171","msg":"trace[32731078] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"268.988706ms","start":"2026-03-18T00:48:20.899765Z","end":"2026-03-18T00:48:21.168753Z","steps":["trace[32731078] 'read index received' (duration: 103.842438ms)","trace[32731078] 'applied index is now lower than readState.Index' (duration: 165.144878ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:48:21.168867Z","caller":"traceutil/trace.go:171","msg":"trace[915919109] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"282.930304ms","start":"2026-03-18T00:48:20.885925Z","end":"2026-03-18T00:48:21.168855Z","steps":["trace[915919109] 'process raft request' (duration: 117.749465ms)","trace[915919109] 'compare' (duration: 164.550184ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:21.16941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.620981ms","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-03-18T00:48:21.169504Z","caller":"traceutil/trace.go:171","msg":"trace[1348543701] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:972; }","duration":"269.745684ms","start":"2026-03-18T00:48:20.899739Z","end":"2026-03-18T00:48:21.169485Z","steps":["trace[1348543701] 'agreement among raft nodes before linearized reading' (duration: 269.504829ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:21.169657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.714084ms","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-03-18T00:48:21.169713Z","caller":"traceutil/trace.go:171","msg":"trace[1859852580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:972; }","duration":"226.824416ms","start":"2026-03-18T00:48:20.942874Z","end":"2026-03-18T00:48:21.169699Z","steps":["trace[1859852580] 'agreement among raft nodes before linearized reading' (duration: 226.690293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:21.169809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.940836ms","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-03-18T00:48:21.170699Z","caller":"traceutil/trace.go:171","msg":"trace[2006472175] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:972; }","duration":"226.880618ms","start":"2026-03-18T00:48:20.943797Z","end":"2026-03-18T00:48:21.170678Z","steps":["trace[2006472175] 'agreement among raft nodes before linearized reading' (duration: 225.785943ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:21.34228Z","caller":"traceutil/trace.go:171","msg":"trace[1239155754] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"168.856185ms","start":"2026-03-18T00:48:21.173399Z","end":"2026-03-18T00:48:21.342255Z","steps":["trace[1239155754] 'process raft request' (duration: 168.535398ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:21.344384Z","caller":"traceutil/trace.go:171","msg":"trace[849934106] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"170.849672ms","start":"2026-03-18T00:48:21.173517Z","end":"2026-03-18T00:48:21.344367Z","steps":["trace[849934106] 'process raft request' (duration: 170.530164ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:21.344795Z","caller":"traceutil/trace.go:171","msg":"trace[1437322180] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"169.754616ms","start":"2026-03-18T00:48:21.174572Z","end":"2026-03-18T00:48:21.344326Z","steps":["trace[1437322180] 'process raft request' (duration: 169.677724ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:49:05.680213Z","caller":"traceutil/trace.go:171","msg":"trace[1263899670] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"102.403241ms","start":"2026-03-18T00:49:05.577788Z","end":"2026-03-18T00:49:05.680191Z","steps":["trace[1263899670] 'process raft request' (duration: 98.697044ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:49:07.245169Z","caller":"traceutil/trace.go:171","msg":"trace[2021446792] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"136.259235ms","start":"2026-03-18T00:49:07.108878Z","end":"2026-03-18T00:49:07.245137Z","steps":["trace[2021446792] 'process raft request' (duration: 89.035787ms)","trace[2021446792] 'compare' (duration: 47.084954ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:49:27.927798Z","caller":"traceutil/trace.go:171","msg":"trace[1475260718] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"112.528708ms","start":"2026-03-18T00:49:27.815227Z","end":"2026-03-18T00:49:27.927755Z","steps":["trace[1475260718] 'process raft request' (duration: 32.296637ms)","trace[1475260718] 'compare' (duration: 80.004655ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:49:33.943642Z","caller":"traceutil/trace.go:171","msg":"trace[1447753709] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"109.47941ms","start":"2026-03-18T00:49:33.834132Z","end":"2026-03-18T00:49:33.943612Z","steps":["trace[1447753709] 'process raft request' (duration: 85.209269ms)","trace[1447753709] 'compare' (duration: 24.034976ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:50:43.153101Z","caller":"traceutil/trace.go:171","msg":"trace[388790585] transaction","detail":"{read_only:false; response_revision:1569; number_of_response:1; }","duration":"158.257647ms","start":"2026-03-18T00:50:42.994809Z","end":"2026-03-18T00:50:43.153067Z","steps":["trace[388790585] 'process raft request' (duration: 158.005301ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:50:57.630758Z","caller":"traceutil/trace.go:171","msg":"trace[1443462146] transaction","detail":"{read_only:false; response_revision:1628; number_of_response:1; }","duration":"116.209702ms","start":"2026-03-18T00:50:57.514522Z","end":"2026-03-18T00:50:57.630732Z","steps":["trace[1443462146] 'process raft request' (duration: 116.10587ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:51:30.844589Z","caller":"traceutil/trace.go:171","msg":"trace[1961061972] linearizableReadLoop","detail":"{readStateIndex:1871; appliedIndex:1870; }","duration":"109.010884ms","start":"2026-03-18T00:51:30.735544Z","end":"2026-03-18T00:51:30.844555Z","steps":["trace[1961061972] 'read index received' (duration: 108.789459ms)","trace[1961061972] 'applied index is now lower than readState.Index' (duration: 220.225µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:51:30.84468Z","caller":"traceutil/trace.go:171","msg":"trace[2064655630] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"134.660196ms","start":"2026-03-18T00:51:30.709969Z","end":"2026-03-18T00:51:30.84463Z","steps":["trace[2064655630] 'process raft request' (duration: 134.392829ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:51:30.844977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.402803ms","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-03-18T00:51:30.84503Z","caller":"traceutil/trace.go:171","msg":"trace[643475019] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1795; }","duration":"109.495476ms","start":"2026-03-18T00:51:30.73552Z","end":"2026-03-18T00:51:30.845015Z","steps":["trace[643475019] 'agreement among raft nodes before linearized reading' (duration: 109.156288ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:51:31.106462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.560298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T00:51:31.107257Z","caller":"traceutil/trace.go:171","msg":"trace[474055760] linearizableReadLoop","detail":"{readStateIndex:1872; appliedIndex:1871; }","duration":"219.129953ms","start":"2026-03-18T00:51:30.888088Z","end":"2026-03-18T00:51:31.107217Z","steps":["trace[474055760] 'read index received' (duration: 90.725896ms)","trace[474055760] 'applied index is now lower than readState.Index' (duration: 128.402287ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:51:31.107317Z","caller":"traceutil/trace.go:171","msg":"trace[1164552191] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"257.277787ms","start":"2026-03-18T00:51:30.850028Z","end":"2026-03-18T00:51:31.107305Z","steps":["trace[1164552191] 'process raft request' (duration: 128.773797ms)","trace[1164552191] 'compare' (duration: 127.383064ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:51:31.10741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.304997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:51:31.107495Z","caller":"traceutil/trace.go:171","msg":"trace[395276997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1796; }","duration":"219.44427ms","start":"2026-03-18T00:51:30.888032Z","end":"2026-03-18T00:51:31.107477Z","steps":["trace[395276997] 'agreement among raft nodes before linearized reading' (duration: 219.293807ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:51:31.14057Z","caller":"traceutil/trace.go:171","msg":"trace[1478782373] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"201.016059ms","start":"2026-03-18T00:51:30.939536Z","end":"2026-03-18T00:51:31.140552Z","steps":["trace[1478782373] 'process raft request' (duration: 200.833725ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:51:31.732176Z","caller":"traceutil/trace.go:171","msg":"trace[1165396379] linearizableReadLoop","detail":"{readStateIndex:1874; appliedIndex:1873; }","duration":"143.556264ms","start":"2026-03-18T00:51:31.588588Z","end":"2026-03-18T00:51:31.732144Z","steps":["trace[1165396379] 'read index received' (duration: 143.344349ms)","trace[1165396379] 'applied index is now lower than readState.Index' (duration: 210.985µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:51:31.732246Z","caller":"traceutil/trace.go:171","msg":"trace[753239317] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"255.064157ms","start":"2026-03-18T00:51:31.477147Z","end":"2026-03-18T00:51:31.732211Z","steps":["trace[753239317] 'process raft request' (duration: 254.852592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:51:31.732378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.774979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-18T00:51:31.732439Z","caller":"traceutil/trace.go:171","msg":"trace[1486668662] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1798; }","duration":"143.871181ms","start":"2026-03-18T00:51:31.58854Z","end":"2026-03-18T00:51:31.732411Z","steps":["trace[1486668662] 'agreement among raft nodes before linearized reading' (duration: 143.703117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:51:32.249763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.700153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T00:51:32.249841Z","caller":"traceutil/trace.go:171","msg":"trace[135274614] linearizableReadLoop","detail":"{readStateIndex:1875; appliedIndex:1874; }","duration":"480.11584ms","start":"2026-03-18T00:51:31.769714Z","end":"2026-03-18T00:51:32.24983Z","steps":["trace[135274614] 'read index received' (duration: 43.271654ms)","trace[135274614] 'applied index is now lower than readState.Index' (duration: 436.843556ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:51:32.249946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.242193ms","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-03-18T00:51:32.249909Z","caller":"traceutil/trace.go:171","msg":"trace[1678280586] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"513.369089ms","start":"2026-03-18T00:51:31.736502Z","end":"2026-03-18T00:51:32.249871Z","steps":["trace[1678280586] 'process raft request' (duration: 76.485312ms)","trace[1678280586] 'compare' (duration: 436.626811ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:51:32.250075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:51:31.736477Z","time spent":"513.527953ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T00:51:32.250076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.892577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-18T00:51:32.250146Z","caller":"traceutil/trace.go:171","msg":"trace[1305557454] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1799; }","duration":"294.005579ms","start":"2026-03-18T00:51:31.956123Z","end":"2026-03-18T00:51:32.250128Z","steps":["trace[1305557454] 'agreement among raft nodes before linearized reading' (duration: 293.778064ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:51:32.249963Z","caller":"traceutil/trace.go:171","msg":"trace[1022098018] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1799; }","duration":"480.290504ms","start":"2026-03-18T00:51:31.769668Z","end":"2026-03-18T00:51:32.249958Z","steps":["trace[1022098018] 'agreement among raft nodes before linearized reading' (duration: 480.213253ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:51:32.250305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:51:31.769647Z","time spent":"480.647902ms","remote":"127.0.0.1:54090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-03-18T00:51:32.454284Z","caller":"traceutil/trace.go:171","msg":"trace[617052865] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"200.051417ms","start":"2026-03-18T00:51:32.254169Z","end":"2026-03-18T00:51:32.45422Z","steps":["trace[617052865] 'process raft request' (duration: 100.519535ms)","trace[617052865] 'compare' (duration: 99.43887ms)"],"step_count":2}