{"level":"warn","ts":"2026-02-24T13:01:19.075415Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T13:01:19.075507Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.79:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.79:2380","--initial-cluster=instance=https://199.19.213.79:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.79:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.79:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-24T13:01:19.075571Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T13:01:19.075579Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.79:2380"]} {"level":"info","ts":"2026-02-24T13:01:19.075594Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-24T13:01:19.076216Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.79:2379"]} {"level":"info","ts":"2026-02-24T13:01:19.07628Z","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.19.213.79:2380"],"listen-peer-urls":["https://199.19.213.79:2380"],"advertise-client-urls":["https://199.19.213.79:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.79:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.79:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-24T13:01:19.079744Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.258838ms"} {"level":"info","ts":"2026-02-24T13:01:19.085632Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d42821602b060932","cluster-id":"2ce6ee5cfaa00f0e"} {"level":"info","ts":"2026-02-24T13:01:19.085724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d42821602b060932 switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T13:01:19.085745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d42821602b060932 became follower at term 0"} {"level":"info","ts":"2026-02-24T13:01:19.085754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d42821602b060932 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T13:01:19.085766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d42821602b060932 became follower at term 1"} {"level":"info","ts":"2026-02-24T13:01:19.085804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d42821602b060932 switched to configuration voters=(15287505632031541554)"} {"level":"warn","ts":"2026-02-24T13:01:19.091752Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T13:01:19.093694Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T13:01:19.094747Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-24T13:01:19.096759Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d42821602b060932","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T13:01:19.096873Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-24T13:01:19.096941Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-24T13:01:19.096953Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-24T13:01:19.097723Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d42821602b060932","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T13:01:19.098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d42821602b060932 switched to configuration voters=(15287505632031541554)"} {"level":"info","ts":"2026-02-24T13:01:19.098089Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2ce6ee5cfaa00f0e","local-member-id":"d42821602b060932","added-peer-id":"d42821602b060932","added-peer-peer-urls":["https://199.19.213.79:2380"]} {"level":"info","ts":"2026-02-24T13:01:19.104124Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-24T13:01:19.104354Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d42821602b060932","initial-advertise-peer-urls":["https://199.19.213.79:2380"],"listen-peer-urls":["https://199.19.213.79:2380"],"advertise-client-urls":["https://199.19.213.79:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.79:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T13:01:19.10439Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T13:01:19.104595Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.79:2380"} {"level":"info","ts":"2026-02-24T13:01:19.104618Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.79:2380"} {"level":"info","ts":"2026-02-24T13:01:19.986844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d42821602b060932 is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T13:01:19.986914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d42821602b060932 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T13:01:19.986936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d42821602b060932 received MsgPreVoteResp from d42821602b060932 at term 1"} {"level":"info","ts":"2026-02-24T13:01:19.986956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d42821602b060932 became candidate at term 2"} {"level":"info","ts":"2026-02-24T13:01:19.986968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d42821602b060932 received MsgVoteResp from d42821602b060932 at term 2"} {"level":"info","ts":"2026-02-24T13:01:19.986984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d42821602b060932 became leader at term 2"} {"level":"info","ts":"2026-02-24T13:01:19.986998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d42821602b060932 elected leader d42821602b060932 at term 2"} {"level":"info","ts":"2026-02-24T13:01:19.988262Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d42821602b060932","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.79:2379]}","request-path":"/0/members/d42821602b060932/attributes","cluster-id":"2ce6ee5cfaa00f0e","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T13:01:19.988328Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T13:01:19.98835Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T13:01:19.98853Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T13:01:19.988573Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T13:01:19.988773Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T13:01:19.989753Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2ce6ee5cfaa00f0e","local-member-id":"d42821602b060932","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T13:01:19.989894Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T13:01:19.989981Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T13:01:19.991554Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.79:2379"} {"level":"info","ts":"2026-02-24T13:01:19.992007Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T13:01:45.305385Z","caller":"traceutil/trace.go:171","msg":"trace[1326565855] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"334.688633ms","start":"2026-02-24T13:01:44.970657Z","end":"2026-02-24T13:01:45.305346Z","steps":["trace[1326565855] 'process raft request' (duration: 334.488517ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:01:45.305463Z","caller":"traceutil/trace.go:171","msg":"trace[619446474] linearizableReadLoop","detail":"{readStateIndex:455; appliedIndex:455; }","duration":"331.949209ms","start":"2026-02-24T13:01:44.973486Z","end":"2026-02-24T13:01:45.305435Z","steps":["trace[619446474] 'read index received' (duration: 331.937969ms)","trace[619446474] 'applied index is now lower than readState.Index' (duration: 8.97µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:01:45.305728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.085942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:01:45.305959Z","caller":"traceutil/trace.go:171","msg":"trace[1055701735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:440; }","duration":"332.474333ms","start":"2026-02-24T13:01:44.973445Z","end":"2026-02-24T13:01:45.30592Z","steps":["trace[1055701735] 'agreement among raft nodes before linearized reading' (duration: 332.076212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:01:45.306038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:01:44.973433Z","time spent":"332.577105ms","remote":"127.0.0.1:41208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T13:01:45.306987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:01:44.970633Z","time spent":"335.216076ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:01:45.307629Z","caller":"traceutil/trace.go:171","msg":"trace[1780899170] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"230.368299ms","start":"2026-02-24T13:01:45.077239Z","end":"2026-02-24T13:01:45.307607Z","steps":["trace[1780899170] 'process raft request' (duration: 230.215495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:01:53.311861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:01:52.833711Z","time spent":"478.147518ms","remote":"127.0.0.1:41250","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-24T13:01:53.31189Z","caller":"traceutil/trace.go:171","msg":"trace[1285501487] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:502; }","duration":"337.033817ms","start":"2026-02-24T13:01:52.974825Z","end":"2026-02-24T13:01:53.311859Z","steps":["trace[1285501487] 'read index received' (duration: 337.021206ms)","trace[1285501487] 'applied index is now lower than readState.Index' (duration: 9.971µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:01:53.312194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.346465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:01:53.312294Z","caller":"traceutil/trace.go:171","msg":"trace[2133208250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:484; }","duration":"337.501949ms","start":"2026-02-24T13:01:52.974765Z","end":"2026-02-24T13:01:53.312267Z","steps":["trace[2133208250] 'agreement among raft nodes before linearized reading' (duration: 337.1929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:01:53.31234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:01:52.974735Z","time spent":"337.596781ms","remote":"127.0.0.1:41208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T13:02:18.099031Z","caller":"traceutil/trace.go:171","msg":"trace[459829606] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"193.110011ms","start":"2026-02-24T13:02:17.9059Z","end":"2026-02-24T13:02:18.09901Z","steps":["trace[459829606] 'read index received' (duration: 192.935576ms)","trace[459829606] 'applied index is now lower than readState.Index' (duration: 173.505µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:02:18.099636Z","caller":"traceutil/trace.go:171","msg":"trace[5637382] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"603.772368ms","start":"2026-02-24T13:02:17.495334Z","end":"2026-02-24T13:02:18.099106Z","steps":["trace[5637382] 'process raft request' (duration: 603.558002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:02:18.099737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.162867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:02:18.099839Z","caller":"traceutil/trace.go:171","msg":"trace[1045047980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"125.296801ms","start":"2026-02-24T13:02:17.974502Z","end":"2026-02-24T13:02:18.099799Z","steps":["trace[1045047980] 'agreement among raft nodes before linearized reading' (duration: 124.633143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:02:18.099906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:02:17.495309Z","time spent":"604.492458ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:02:18.101057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.155377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T13:02:18.101172Z","caller":"traceutil/trace.go:171","msg":"trace[1219119402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"195.282911ms","start":"2026-02-24T13:02:17.905845Z","end":"2026-02-24T13:02:18.101128Z","steps":["trace[1219119402] 'agreement among raft nodes before linearized reading' (duration: 193.295087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:02:18.97199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.155516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:02:18.97241Z","caller":"traceutil/trace.go:171","msg":"trace[997573828] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"865.03645ms","start":"2026-02-24T13:02:18.107344Z","end":"2026-02-24T13:02:18.97238Z","steps":["trace[997573828] 'process raft request' (duration: 347.289307ms)","trace[997573828] 'compare' (duration: 517.044063ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:02:18.972567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:02:18.107305Z","time spent":"865.168053ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:02:40.583122Z","caller":"traceutil/trace.go:171","msg":"trace[423078107] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"393.599083ms","start":"2026-02-24T13:02:40.189508Z","end":"2026-02-24T13:02:40.583107Z","steps":["trace[423078107] 'process raft request' (duration: 393.50164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:02:40.583254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:02:40.189495Z","time spent":"393.709826ms","remote":"127.0.0.1:41460","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-02-24T13:02:43.047892Z","caller":"traceutil/trace.go:171","msg":"trace[1433533537] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"172.963034ms","start":"2026-02-24T13:02:42.874898Z","end":"2026-02-24T13:02:43.047861Z","steps":["trace[1433533537] 'process raft request' (duration: 92.061819ms)","trace[1433533537] 'compare' (duration: 80.69211ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:02:45.435812Z","caller":"traceutil/trace.go:171","msg":"trace[1927777942] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"198.318953ms","start":"2026-02-24T13:02:45.237469Z","end":"2026-02-24T13:02:45.435788Z","steps":["trace[1927777942] 'process raft request' (duration: 198.177279ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:15.19318Z","caller":"traceutil/trace.go:171","msg":"trace[164010494] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:684; }","duration":"347.595255ms","start":"2026-02-24T13:03:14.845565Z","end":"2026-02-24T13:03:15.193161Z","steps":["trace[164010494] 'read index received' (duration: 347.526413ms)","trace[164010494] 'applied index is now lower than readState.Index' (duration: 68.132µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:03:15.193277Z","caller":"traceutil/trace.go:171","msg":"trace[1332564018] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"355.771358ms","start":"2026-02-24T13:03:14.837496Z","end":"2026-02-24T13:03:15.193268Z","steps":["trace[1332564018] 'process raft request' (duration: 355.547772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:15.193381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:14.837474Z","time spent":"355.844891ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:03:15.193643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.062098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T13:03:15.193743Z","caller":"traceutil/trace.go:171","msg":"trace[2082739748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"348.194602ms","start":"2026-02-24T13:03:14.845523Z","end":"2026-02-24T13:03:15.193718Z","steps":["trace[2082739748] 'agreement among raft nodes before linearized reading' (duration: 347.934604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:15.193803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:14.845504Z","time spent":"348.285953ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T13:03:15.193874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.803652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:03:15.194148Z","caller":"traceutil/trace.go:171","msg":"trace[421190103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"206.11824ms","start":"2026-02-24T13:03:14.98789Z","end":"2026-02-24T13:03:15.194008Z","steps":["trace[421190103] 'agreement among raft nodes before linearized reading' (duration: 205.766681ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:16.114Z","caller":"traceutil/trace.go:171","msg":"trace[167887961] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"484.719111ms","start":"2026-02-24T13:03:15.62925Z","end":"2026-02-24T13:03:16.113969Z","steps":["trace[167887961] 'process raft request' (duration: 484.501115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:16.114167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:15.629236Z","time spent":"484.846814ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:03:16.114208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.588613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:03:16.114258Z","caller":"traceutil/trace.go:171","msg":"trace[850620136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"139.686536ms","start":"2026-02-24T13:03:15.974561Z","end":"2026-02-24T13:03:16.114247Z","steps":["trace[850620136] 'agreement among raft nodes before linearized reading' (duration: 139.604724ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:16.114114Z","caller":"traceutil/trace.go:171","msg":"trace[194271618] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"139.273595ms","start":"2026-02-24T13:03:15.97461Z","end":"2026-02-24T13:03:16.113884Z","steps":["trace[194271618] 'read index received' (duration: 138.939066ms)","trace[194271618] 'applied index is now lower than readState.Index' (duration: 333.369µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:18.340504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.351701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T13:03:18.340583Z","caller":"traceutil/trace.go:171","msg":"trace[1008022694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:666; }","duration":"223.476053ms","start":"2026-02-24T13:03:18.117089Z","end":"2026-02-24T13:03:18.340566Z","steps":["trace[1008022694] 'range keys from in-memory index tree' (duration: 223.196366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:18.3405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.818949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-24T13:03:18.34075Z","caller":"traceutil/trace.go:171","msg":"trace[793643173] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:666; }","duration":"218.048686ms","start":"2026-02-24T13:03:18.12264Z","end":"2026-02-24T13:03:18.340689Z","steps":["trace[793643173] 'range keys from in-memory index tree' (duration: 217.761098ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:26.271253Z","caller":"traceutil/trace.go:171","msg":"trace[1388306354] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"324.001921ms","start":"2026-02-24T13:03:25.947232Z","end":"2026-02-24T13:03:26.271234Z","steps":["trace[1388306354] 'process raft request' (duration: 323.878977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:26.271374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:25.9472Z","time spent":"324.114364ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18231,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:03:28.832048Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":662764236348421178,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T13:03:29.130908Z","caller":"traceutil/trace.go:171","msg":"trace[1753699322] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"799.226901ms","start":"2026-02-24T13:03:28.331651Z","end":"2026-02-24T13:03:29.130878Z","steps":["trace[1753699322] 'read index received' (duration: 794.749188ms)","trace[1753699322] 'applied index is now lower than readState.Index' (duration: 4.476283ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:29.131094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.413166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T13:03:29.131167Z","caller":"traceutil/trace.go:171","msg":"trace[1875422453] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:798; }","duration":"799.56029ms","start":"2026-02-24T13:03:28.331595Z","end":"2026-02-24T13:03:29.131155Z","steps":["trace[1875422453] 'agreement among raft nodes before linearized reading' (duration: 799.400116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:29.131193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.286006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-24T13:03:29.131202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:28.331575Z","time spent":"799.619571ms","remote":"127.0.0.1:41728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-02-24T13:03:29.131236Z","caller":"traceutil/trace.go:171","msg":"trace[635074815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"738.373399ms","start":"2026-02-24T13:03:28.392852Z","end":"2026-02-24T13:03:29.131226Z","steps":["trace[635074815] 'agreement among raft nodes before linearized reading' (duration: 738.192284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:29.131272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:28.392837Z","time spent":"738.42624ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T13:03:29.131298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.579967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T13:03:29.131392Z","caller":"traceutil/trace.go:171","msg":"trace[1175317379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"491.708391ms","start":"2026-02-24T13:03:28.639658Z","end":"2026-02-24T13:03:29.131367Z","steps":["trace[1175317379] 'agreement among raft nodes before linearized reading' (duration: 491.430033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:29.131444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:28.639638Z","time spent":"491.795883ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T13:03:29.131553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.801772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T13:03:29.131573Z","caller":"traceutil/trace.go:171","msg":"trace[836467743] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"674.862323ms","start":"2026-02-24T13:03:28.456705Z","end":"2026-02-24T13:03:29.131568Z","steps":["trace[836467743] 'agreement among raft nodes before linearized reading' (duration: 674.768221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:29.131593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:28.456683Z","time spent":"674.905785ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T13:03:29.131667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.01591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T13:03:29.131725Z","caller":"traceutil/trace.go:171","msg":"trace[1065329423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"492.103331ms","start":"2026-02-24T13:03:28.639605Z","end":"2026-02-24T13:03:29.131708Z","steps":["trace[1065329423] 'agreement among raft nodes before linearized reading' (duration: 491.948897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:29.131783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:28.639589Z","time spent":"492.182274ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T13:03:29.413505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.361171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:03:29.413665Z","caller":"traceutil/trace.go:171","msg":"trace[408655999] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"276.655377ms","start":"2026-02-24T13:03:29.136996Z","end":"2026-02-24T13:03:29.413651Z","steps":["trace[408655999] 'process raft request' (duration: 276.590275ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:29.413793Z","caller":"traceutil/trace.go:171","msg":"trace[506219016] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"276.953025ms","start":"2026-02-24T13:03:29.136815Z","end":"2026-02-24T13:03:29.413768Z","steps":["trace[506219016] 'process raft request' (duration: 135.235584ms)","trace[506219016] 'compare' (duration: 141.228628ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:03:29.413883Z","caller":"traceutil/trace.go:171","msg":"trace[162426218] linearizableReadLoop","detail":"{readStateIndex:842; appliedIndex:839; }","duration":"143.078509ms","start":"2026-02-24T13:03:29.270786Z","end":"2026-02-24T13:03:29.413865Z","steps":["trace[162426218] 'read index received' (duration: 1.204083ms)","trace[162426218] 'applied index is now lower than readState.Index' (duration: 141.873546ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:29.414063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.273644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-24T13:03:29.414074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.313279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:03:29.414095Z","caller":"traceutil/trace.go:171","msg":"trace[1593624861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:802; }","duration":"143.343876ms","start":"2026-02-24T13:03:29.270744Z","end":"2026-02-24T13:03:29.414088Z","steps":["trace[1593624861] 'agreement among raft nodes before linearized reading' (duration: 143.227533ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:29.414112Z","caller":"traceutil/trace.go:171","msg":"trace[2139663593] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:802; }","duration":"138.37383ms","start":"2026-02-24T13:03:29.27572Z","end":"2026-02-24T13:03:29.414094Z","steps":["trace[2139663593] 'agreement among raft nodes before linearized reading' (duration: 138.311298ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:29.414259Z","caller":"traceutil/trace.go:171","msg":"trace[888145124] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"275.66083ms","start":"2026-02-24T13:03:29.138591Z","end":"2026-02-24T13:03:29.414252Z","steps":["trace[888145124] 'process raft request' (duration: 275.033213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:29.414285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.113053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:03:29.414312Z","caller":"traceutil/trace.go:171","msg":"trace[1725322119] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:802; }","duration":"138.215596ms","start":"2026-02-24T13:03:29.27609Z","end":"2026-02-24T13:03:29.414306Z","steps":["trace[1725322119] 'agreement among raft nodes before linearized reading' (duration: 138.174305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:29.414403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.620727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:03:29.414425Z","caller":"traceutil/trace.go:171","msg":"trace[1943293189] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:802; }","duration":"138.658958ms","start":"2026-02-24T13:03:29.275761Z","end":"2026-02-24T13:03:29.41442Z","steps":["trace[1943293189] 'agreement among raft nodes before linearized reading' (duration: 138.625307ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:34.271329Z","caller":"traceutil/trace.go:171","msg":"trace[778827349] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:895; }","duration":"313.185695ms","start":"2026-02-24T13:03:33.958105Z","end":"2026-02-24T13:03:34.271291Z","steps":["trace[778827349] 'read index received' (duration: 312.886967ms)","trace[778827349] 'applied index is now lower than readState.Index' (duration: 296.848µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:03:34.27141Z","caller":"traceutil/trace.go:171","msg":"trace[903689337] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"460.647534ms","start":"2026-02-24T13:03:33.810735Z","end":"2026-02-24T13:03:34.271383Z","steps":["trace[903689337] 'process raft request' (duration: 460.299854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:34.271528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:33.810716Z","time spent":"460.749286ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:03:34.271651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.520745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-rq4rr\" ","response":"range_response_count:1 size:4823"} {"level":"info","ts":"2026-02-24T13:03:34.271695Z","caller":"traceutil/trace.go:171","msg":"trace[1918485039] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-rq4rr; range_end:; response_count:1; response_revision:855; }","duration":"313.636198ms","start":"2026-02-24T13:03:33.958046Z","end":"2026-02-24T13:03:34.271683Z","steps":["trace[1918485039] 'agreement among raft nodes before linearized reading' (duration: 313.403542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:34.271738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:33.958027Z","time spent":"313.700749ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4846,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-rq4rr\" "} {"level":"info","ts":"2026-02-24T13:03:34.287066Z","caller":"traceutil/trace.go:171","msg":"trace[177631114] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"328.203165ms","start":"2026-02-24T13:03:33.958846Z","end":"2026-02-24T13:03:34.287049Z","steps":["trace[177631114] 'process raft request' (duration: 328.076072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:34.287212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:33.958829Z","time spent":"328.311708ms","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T13:03:35.577529Z","caller":"traceutil/trace.go:171","msg":"trace[1322159676] linearizableReadLoop","detail":"{readStateIndex:901; appliedIndex:898; }","duration":"111.053943ms","start":"2026-02-24T13:03:35.466437Z","end":"2026-02-24T13:03:35.577491Z","steps":["trace[1322159676] 'read index received' (duration: 34.480222ms)","trace[1322159676] 'applied index is now lower than readState.Index' (duration: 76.568891ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:03:35.577568Z","caller":"traceutil/trace.go:171","msg":"trace[1178208942] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"138.37029ms","start":"2026-02-24T13:03:35.439172Z","end":"2026-02-24T13:03:35.577542Z","steps":["trace[1178208942] 'process raft request' (duration: 133.299021ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:35.577609Z","caller":"traceutil/trace.go:171","msg":"trace[1853651277] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"136.729075ms","start":"2026-02-24T13:03:35.440851Z","end":"2026-02-24T13:03:35.57758Z","steps":["trace[1853651277] 'process raft request' (duration: 136.574151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:35.577833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.349101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T13:03:35.577892Z","caller":"traceutil/trace.go:171","msg":"trace[2135053279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:860; }","duration":"111.473195ms","start":"2026-02-24T13:03:35.466404Z","end":"2026-02-24T13:03:35.577877Z","steps":["trace[2135053279] 'agreement among raft nodes before linearized reading' (duration: 111.234888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:45.115644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.105137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:03:45.115755Z","caller":"traceutil/trace.go:171","msg":"trace[1392015759] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:944; }","duration":"139.890191ms","start":"2026-02-24T13:03:44.975849Z","end":"2026-02-24T13:03:45.115739Z","steps":["trace[1392015759] 'read index received' (duration: 33.681µs)","trace[1392015759] 'applied index is now lower than readState.Index' (duration: 139.85525ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:45.11583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.984233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:03:45.115862Z","caller":"traceutil/trace.go:171","msg":"trace[1721506979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"140.043895ms","start":"2026-02-24T13:03:44.975803Z","end":"2026-02-24T13:03:45.115847Z","steps":["trace[1721506979] 'agreement among raft nodes before linearized reading' (duration: 139.993424ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:45.115841Z","caller":"traceutil/trace.go:171","msg":"trace[697013852] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"369.06025ms","start":"2026-02-24T13:03:44.746724Z","end":"2026-02-24T13:03:45.115784Z","steps":["trace[697013852] 'process raft request' (duration: 120.731028ms)","trace[697013852] 'compare' (duration: 247.961302ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:45.116039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:44.746702Z","time spent":"369.235615ms","remote":"127.0.0.1:41582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:03:45.731389Z","caller":"traceutil/trace.go:171","msg":"trace[478286352] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"123.335489ms","start":"2026-02-24T13:03:45.608028Z","end":"2026-02-24T13:03:45.731363Z","steps":["trace[478286352] 'process raft request' (duration: 123.185415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:46.001134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.973007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:03:46.001252Z","caller":"traceutil/trace.go:171","msg":"trace[680140951] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"242.851454ms","start":"2026-02-24T13:03:45.758381Z","end":"2026-02-24T13:03:46.001232Z","steps":["trace[680140951] 'read index received' (duration: 103.578699ms)","trace[680140951] 'applied index is now lower than readState.Index' (duration: 139.271615ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:03:46.001359Z","caller":"traceutil/trace.go:171","msg":"trace[611220939] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"245.349332ms","start":"2026-02-24T13:03:45.75597Z","end":"2026-02-24T13:03:46.001319Z","steps":["trace[611220939] 'process raft request' (duration: 106.108369ms)","trace[611220939] 'compare' (duration: 138.839722ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:46.001434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.145237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T13:03:46.001459Z","caller":"traceutil/trace.go:171","msg":"trace[442454630] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:904; }","duration":"211.195979ms","start":"2026-02-24T13:03:45.790256Z","end":"2026-02-24T13:03:46.001452Z","steps":["trace[442454630] 'agreement among raft nodes before linearized reading' (duration: 211.082556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:46.00155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.145842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T13:03:46.001697Z","caller":"traceutil/trace.go:171","msg":"trace[609193653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:904; }","duration":"243.336737ms","start":"2026-02-24T13:03:45.758339Z","end":"2026-02-24T13:03:46.001676Z","steps":["trace[609193653] 'agreement among raft nodes before linearized reading' (duration: 242.988698ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:46.159045Z","caller":"traceutil/trace.go:171","msg":"trace[1443103287] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"153.289177ms","start":"2026-02-24T13:03:46.005726Z","end":"2026-02-24T13:03:46.159015Z","steps":["trace[1443103287] 'process raft request' (duration: 117.745986ms)","trace[1443103287] 'compare' (duration: 35.334705ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:03:47.530164Z","caller":"traceutil/trace.go:171","msg":"trace[814471273] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"198.162223ms","start":"2026-02-24T13:03:47.331978Z","end":"2026-02-24T13:03:47.53014Z","steps":["trace[814471273] 'process raft request' (duration: 197.965338ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:47.704592Z","caller":"traceutil/trace.go:171","msg":"trace[66982770] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"232.358157ms","start":"2026-02-24T13:03:47.47221Z","end":"2026-02-24T13:03:47.704569Z","steps":["trace[66982770] 'process raft request' (duration: 189.421214ms)","trace[66982770] 'compare' (duration: 42.842461ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:03:47.872194Z","caller":"traceutil/trace.go:171","msg":"trace[914387586] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"133.389444ms","start":"2026-02-24T13:03:47.738777Z","end":"2026-02-24T13:03:47.872166Z","steps":["trace[914387586] 'process raft request' (duration: 80.561761ms)","trace[914387586] 'compare' (duration: 52.70049ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:03:48.253544Z","caller":"traceutil/trace.go:171","msg":"trace[555972056] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"174.110736ms","start":"2026-02-24T13:03:48.079412Z","end":"2026-02-24T13:03:48.253523Z","steps":["trace[555972056] 'process raft request' (duration: 122.452515ms)","trace[555972056] 'compare' (duration: 51.300771ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:03:48.41481Z","caller":"traceutil/trace.go:171","msg":"trace[717115130] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"157.005488ms","start":"2026-02-24T13:03:48.257767Z","end":"2026-02-24T13:03:48.414772Z","steps":["trace[717115130] 'process raft request' (duration: 119.059302ms)","trace[717115130] 'compare' (duration: 37.756691ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:48.545279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.562757ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T13:03:48.54552Z","caller":"traceutil/trace.go:171","msg":"trace[1776508677] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:957; }","duration":"199.282513ms","start":"2026-02-24T13:03:48.346212Z","end":"2026-02-24T13:03:48.545494Z","steps":["trace[1776508677] 'read index received' (duration: 30.648777ms)","trace[1776508677] 'applied index is now lower than readState.Index' (duration: 168.631216ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:48.545727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.479509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:03:48.545826Z","caller":"traceutil/trace.go:171","msg":"trace[1412216179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:914; }","duration":"199.642994ms","start":"2026-02-24T13:03:48.346162Z","end":"2026-02-24T13:03:48.545805Z","steps":["trace[1412216179] 'agreement among raft nodes before linearized reading' (duration: 199.479339ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:49.813137Z","caller":"traceutil/trace.go:171","msg":"trace[1369414240] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"275.849775ms","start":"2026-02-24T13:03:49.537267Z","end":"2026-02-24T13:03:49.813117Z","steps":["trace[1369414240] 'process raft request' (duration: 275.729631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:50.476322Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":662764236348421421,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T13:03:50.977363Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":662764236348421421,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T13:03:51.333142Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.452506185s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T13:03:51.33366Z","caller":"traceutil/trace.go:171","msg":"trace[1198465141] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"1.357859361s","start":"2026-02-24T13:03:49.975777Z","end":"2026-02-24T13:03:51.333636Z","steps":["trace[1198465141] 'read index received' (duration: 1.357617294s)","trace[1198465141] 'applied index is now lower than readState.Index' (duration: 241.047µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:03:51.333722Z","caller":"traceutil/trace.go:171","msg":"trace[1221704629] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"1.453167263s","start":"2026-02-24T13:03:49.880518Z","end":"2026-02-24T13:03:51.333685Z","steps":["trace[1221704629] 'process raft request' (duration: 1.452963558s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:51.333824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358067476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:03:51.333867Z","caller":"traceutil/trace.go:171","msg":"trace[149567342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"1.35818585s","start":"2026-02-24T13:03:49.97567Z","end":"2026-02-24T13:03:51.333856Z","steps":["trace[149567342] 'agreement among raft nodes before linearized reading' (duration: 1.358100498s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:51.33388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:49.88049Z","time spent":"1.453310707s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:03:51.333906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:49.975635Z","time spent":"1.358264032s","remote":"127.0.0.1:41208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T13:03:51.334092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050405892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-24T13:03:51.334151Z","caller":"traceutil/trace.go:171","msg":"trace[1720030825] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:918; }","duration":"1.050538345s","start":"2026-02-24T13:03:50.283597Z","end":"2026-02-24T13:03:51.334135Z","steps":["trace[1720030825] 'agreement among raft nodes before linearized reading' (duration: 1.05035536s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:51.334119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076308349s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-24T13:03:51.334263Z","caller":"traceutil/trace.go:171","msg":"trace[953717292] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:918; }","duration":"1.076510134s","start":"2026-02-24T13:03:50.257733Z","end":"2026-02-24T13:03:51.334244Z","steps":["trace[953717292] 'agreement among raft nodes before linearized reading' (duration: 1.076260118s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:51.334194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:50.283567Z","time spent":"1.050618898s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T13:03:51.33437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:50.257703Z","time spent":"1.076651338s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T13:03:51.334589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210187587s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T13:03:51.334679Z","caller":"traceutil/trace.go:171","msg":"trace[871419247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:918; }","duration":"1.210298279s","start":"2026-02-24T13:03:50.124335Z","end":"2026-02-24T13:03:51.334634Z","steps":["trace[871419247] 'agreement among raft nodes before linearized reading' (duration: 1.20958813s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:51.334709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:50.124305Z","time spent":"1.210397822s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T13:03:52.422702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.863376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:03:52.422865Z","caller":"traceutil/trace.go:171","msg":"trace[480234335] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:963; }","duration":"1.080328371s","start":"2026-02-24T13:03:51.342523Z","end":"2026-02-24T13:03:52.422851Z","steps":["trace[480234335] 'read index received' (duration: 212.379432ms)","trace[480234335] 'applied index is now lower than readState.Index' (duration: 867.948239ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:03:52.422938Z","caller":"traceutil/trace.go:171","msg":"trace[1297911029] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"1.08286165s","start":"2026-02-24T13:03:51.340069Z","end":"2026-02-24T13:03:52.422931Z","steps":["trace[1297911029] 'process raft request' (duration: 1.082755717s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:52.422984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:51.34005Z","time spent":"1.082911261s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:03:52.423167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.384714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T13:03:52.423247Z","caller":"traceutil/trace.go:171","msg":"trace[1207882139] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:921; }","duration":"606.497977ms","start":"2026-02-24T13:03:51.81673Z","end":"2026-02-24T13:03:52.423228Z","steps":["trace[1207882139] 'agreement among raft nodes before linearized reading' (duration: 606.23174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:52.423294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:51.816713Z","time spent":"606.571789ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T13:03:52.423378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080827654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:03:52.423458Z","caller":"traceutil/trace.go:171","msg":"trace[2080652608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:921; }","duration":"1.080937227s","start":"2026-02-24T13:03:51.342503Z","end":"2026-02-24T13:03:52.42344Z","steps":["trace[2080652608] 'agreement among raft nodes before linearized reading' (duration: 1.080809123s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:52.423506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:51.342495Z","time spent":"1.081002728s","remote":"127.0.0.1:41208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T13:03:52.423407Z","caller":"traceutil/trace.go:171","msg":"trace[1433582370] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"1.085013569s","start":"2026-02-24T13:03:51.338382Z","end":"2026-02-24T13:03:52.423396Z","steps":["trace[1433582370] 'process raft request' (duration: 216.384131ms)","trace[1433582370] 'compare' (duration: 867.770724ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:52.423691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:51.338363Z","time spent":"1.085210443s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:03:52.423873Z","caller":"traceutil/trace.go:171","msg":"trace[30155015] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"1.084112354s","start":"2026-02-24T13:03:51.338809Z","end":"2026-02-24T13:03:52.422921Z","steps":["trace[30155015] 'process raft request' (duration: 1.08398391s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:52.42396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:51.338789Z","time spent":"1.085125391s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:03:52.939394Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":662764236348421431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T13:03:53.356496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.285449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:03:53.356591Z","caller":"traceutil/trace.go:171","msg":"trace[1758299184] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"917.940995ms","start":"2026-02-24T13:03:52.438634Z","end":"2026-02-24T13:03:53.356575Z","steps":["trace[1758299184] 'read index received' (duration: 567.460291ms)","trace[1758299184] 'applied index is now lower than readState.Index' (duration: 350.479274ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:53.356701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.052288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T13:03:53.356758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.348715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.79\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T13:03:53.356777Z","caller":"traceutil/trace.go:171","msg":"trace[1013684808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"918.147131ms","start":"2026-02-24T13:03:52.438614Z","end":"2026-02-24T13:03:53.356761Z","steps":["trace[1013684808] 'agreement among raft nodes before linearized reading' (duration: 918.040628ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:53.356785Z","caller":"traceutil/trace.go:171","msg":"trace[1915328873] range","detail":"{range_begin:/registry/masterleases/199.19.213.79; range_end:; response_count:1; response_revision:922; }","duration":"516.412337ms","start":"2026-02-24T13:03:52.840364Z","end":"2026-02-24T13:03:53.356776Z","steps":["trace[1915328873] 'agreement among raft nodes before linearized reading' (duration: 516.298834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:53.356814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:52.438588Z","time spent":"918.217842ms","remote":"127.0.0.1:41208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T13:03:53.356817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:52.840342Z","time spent":"516.468798ms","remote":"127.0.0.1:41250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.79\" "} {"level":"info","ts":"2026-02-24T13:03:53.356983Z","caller":"traceutil/trace.go:171","msg":"trace[1039141851] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"926.261723ms","start":"2026-02-24T13:03:52.430709Z","end":"2026-02-24T13:03:53.356971Z","steps":["trace[1039141851] 'process raft request' (duration: 575.403338ms)","trace[1039141851] 'compare' (duration: 350.026692ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:53.357064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:52.430697Z","time spent":"926.311194ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:03:54.277679Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":662764236348421434,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T13:03:54.469537Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.111303336s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T13:03:55.778288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000764142s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-24T13:03:55.778945Z","caller":"traceutil/trace.go:171","msg":"trace[1113725326] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001477052s","start":"2026-02-24T13:03:53.777454Z","end":"2026-02-24T13:03:55.778931Z","steps":["trace[1113725326] 'agreement among raft nodes before linearized reading' (duration: 2.000788163s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:55.779005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:53.777436Z","time spent":"2.001555594s","remote":"127.0.0.1:41208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/24 13:03:55 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T13:03:55.976167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000360851s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-24T13:03:55.976248Z","caller":"traceutil/trace.go:171","msg":"trace[1028594810] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000503574s","start":"2026-02-24T13:03:53.975726Z","end":"2026-02-24T13:03:55.97623Z","steps":["trace[1028594810] 'agreement among raft nodes before linearized reading' (duration: 2.000403172s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:55.976298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:53.975691Z","time spent":"2.000596347s","remote":"127.0.0.1:41196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/24 13:03:55 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T13:03:56.223573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157253142s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:03:56.223693Z","caller":"traceutil/trace.go:171","msg":"trace[1033364163] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:922; }","duration":"1.157404906s","start":"2026-02-24T13:03:55.066271Z","end":"2026-02-24T13:03:56.223676Z","steps":["trace[1033364163] 'range keys from in-memory index tree' (duration: 1.157234842s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:56.224299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.754543097s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-24T13:03:56.224395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:53.358091Z","time spent":"2.866299416s","remote":"127.0.0.1:41250","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-24T13:03:57.171396Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.232458963s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T13:03:57.1722Z","caller":"traceutil/trace.go:171","msg":"trace[2125382136] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"3.810504178s","start":"2026-02-24T13:03:53.361671Z","end":"2026-02-24T13:03:57.172175Z","steps":["trace[2125382136] 'process raft request' (duration: 3.809996464s)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:03:57.172221Z","caller":"traceutil/trace.go:171","msg":"trace[885361921] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:967; }","duration":"3.394710459s","start":"2026-02-24T13:03:53.777494Z","end":"2026-02-24T13:03:57.172204Z","steps":["trace[885361921] 'read index received' (duration: 692.218808ms)","trace[885361921] 'applied index is now lower than readState.Index' (duration: 2.702490721s)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:57.172337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:53.361648Z","time spent":"3.810609991s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:03:57.172346Z","caller":"traceutil/trace.go:171","msg":"trace[927638300] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"2.721280025s","start":"2026-02-24T13:03:54.45103Z","end":"2026-02-24T13:03:57.17231Z","steps":["trace[927638300] 'process raft request' (duration: 2.720976807s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:57.172524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:54.451012Z","time spent":"2.721420759s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:03:57.984374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000776643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-24T13:03:57.984457Z","caller":"traceutil/trace.go:171","msg":"trace[1704374784] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000915206s","start":"2026-02-24T13:03:55.983525Z","end":"2026-02-24T13:03:57.98444Z","steps":["trace[1704374784] 'agreement among raft nodes before linearized reading' (duration: 2.000806433s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:57.984518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:55.983509Z","time spent":"2.000983688s","remote":"127.0.0.1:41208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/24 13:03:57 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T13:03:58.303529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.557076ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-24T13:03:58.3037Z","caller":"traceutil/trace.go:171","msg":"trace[577836132] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:970; }","duration":"1.131432525s","start":"2026-02-24T13:03:57.172256Z","end":"2026-02-24T13:03:58.303689Z","steps":["trace[577836132] 'read index received' (duration: 218.791356ms)","trace[577836132] 'applied index is now lower than readState.Index' (duration: 912.640339ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:58.303754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:55.780168Z","time spent":"2.523584184s","remote":"127.0.0.1:41290","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-24T13:03:58.303892Z","caller":"traceutil/trace.go:171","msg":"trace[2097112652] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"2.078719061s","start":"2026-02-24T13:03:56.225165Z","end":"2026-02-24T13:03:58.303884Z","steps":["trace[2097112652] 'process raft request' (duration: 2.078426923s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:58.303958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:56.22514Z","time spent":"2.078774103s","remote":"127.0.0.1:41250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:03:58.304149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.871462722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-24T13:03:58.304176Z","caller":"traceutil/trace.go:171","msg":"trace[1725304398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:925; }","duration":"3.871519384s","start":"2026-02-24T13:03:54.432651Z","end":"2026-02-24T13:03:58.30417Z","steps":["trace[1725304398] 'agreement among raft nodes before linearized reading' (duration: 3.871409061s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:58.304196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:54.432634Z","time spent":"3.871558135s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T13:03:58.304416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.942135798s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T13:03:58.304437Z","caller":"traceutil/trace.go:171","msg":"trace[1647711818] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:925; }","duration":"2.942184038s","start":"2026-02-24T13:03:55.362248Z","end":"2026-02-24T13:03:58.304432Z","steps":["trace[1647711818] 'agreement among raft nodes before linearized reading' (duration: 2.942127347s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:58.304454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:55.362233Z","time spent":"2.94221731s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T13:03:58.304538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.870933398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T13:03:58.30456Z","caller":"traceutil/trace.go:171","msg":"trace[1241781491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:925; }","duration":"3.8709897s","start":"2026-02-24T13:03:54.433565Z","end":"2026-02-24T13:03:58.304555Z","steps":["trace[1241781491] 'agreement among raft nodes before linearized reading' (duration: 3.870937308s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:58.304576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:54.433543Z","time spent":"3.871029961s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T13:03:58.304968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.871865373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T13:03:58.305042Z","caller":"traceutil/trace.go:171","msg":"trace[1837429797] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:925; }","duration":"3.871994058s","start":"2026-02-24T13:03:54.433032Z","end":"2026-02-24T13:03:58.305026Z","steps":["trace[1837429797] 'agreement among raft nodes before linearized reading' (duration: 3.871015931s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:58.305079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:54.433026Z","time spent":"3.872045599s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T13:03:58.803983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":662764236348421446,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T13:03:58.947738Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.192604388s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T13:03:59.274412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.398087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:03:59.274515Z","caller":"traceutil/trace.go:171","msg":"trace[1194794524] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"1.519483397s","start":"2026-02-24T13:03:57.755012Z","end":"2026-02-24T13:03:59.274495Z","steps":["trace[1194794524] 'process raft request' (duration: 1.192896896s)","trace[1194794524] 'compare' (duration: 326.204451ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:03:59.274601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:57.754984Z","time spent":"1.519566719s","remote":"127.0.0.1:41388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5379,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:03:59.985546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000725632s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-24T13:03:59.985632Z","caller":"traceutil/trace.go:171","msg":"trace[678349158] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000862356s","start":"2026-02-24T13:03:57.984752Z","end":"2026-02-24T13:03:59.985614Z","steps":["trace[678349158] 'agreement among raft nodes before linearized reading' (duration: 2.000750293s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:03:59.985686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:57.98474Z","time spent":"2.000936927s","remote":"127.0.0.1:41196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/24 13:03:59 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T13:04:00.578101Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.629988105s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T13:04:00.578584Z","caller":"traceutil/trace.go:171","msg":"trace[107702865] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"2.597090743s","start":"2026-02-24T13:03:57.981461Z","end":"2026-02-24T13:04:00.578551Z","steps":["trace[107702865] 'process raft request' (duration: 2.596785125s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:00.578821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:57.981427Z","time spent":"2.597235827s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:01.99453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000047093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-24T13:04:01.994624Z","caller":"traceutil/trace.go:171","msg":"trace[587305535] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000211637s","start":"2026-02-24T13:03:59.994394Z","end":"2026-02-24T13:04:01.994606Z","steps":["trace[587305535] 'agreement among raft nodes before linearized reading' (duration: 2.000089093s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:01.994681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:59.994343Z","time spent":"2.00032503s","remote":"127.0.0.1:41208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/24 13:04:01 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T13:04:02.870269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.291812692s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T13:04:02.870878Z","caller":"traceutil/trace.go:171","msg":"trace[1095188812] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"4.564905895s","start":"2026-02-24T13:03:58.30595Z","end":"2026-02-24T13:04:02.870856Z","steps":["trace[1095188812] 'process raft request' (duration: 4.564424211s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:02.870975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:58.30593Z","time spent":"4.564993077s","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T13:04:03.311778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:58.313511Z","time spent":"4.998260563s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-24T13:04:03.311864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:58.313427Z","time spent":"4.998436088s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-24T13:04:03.312169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:58.312992Z","time spent":"4.999167818s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-24T13:04:03.510637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.724265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:04:03.511205Z","caller":"traceutil/trace.go:171","msg":"trace[97872073] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"636.329612ms","start":"2026-02-24T13:04:02.874859Z","end":"2026-02-24T13:04:03.511188Z","steps":["trace[97872073] 'process raft request' (duration: 636.105396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:03.511287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:02.874843Z","time spent":"636.412275ms","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T13:04:03.51129Z","caller":"traceutil/trace.go:171","msg":"trace[698056369] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:972; }","duration":"5.207546849s","start":"2026-02-24T13:03:58.303726Z","end":"2026-02-24T13:04:03.511273Z","steps":["trace[698056369] 'read index received' (duration: 644.388692ms)","trace[698056369] 'applied index is now lower than readState.Index' (duration: 4.563156607s)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:03.511421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.200804135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-24T13:04:03.51145Z","caller":"traceutil/trace.go:171","msg":"trace[1046954063] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:933; }","duration":"5.200873247s","start":"2026-02-24T13:03:58.310567Z","end":"2026-02-24T13:04:03.51144Z","steps":["trace[1046954063] 'agreement among raft nodes before linearized reading' (duration: 5.200800205s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:03.511429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.581585448s","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":"warn","ts":"2026-02-24T13:04:03.511482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:58.310554Z","time spent":"5.200917919s","remote":"127.0.0.1:41382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-02-24T13:04:03.511495Z","caller":"traceutil/trace.go:171","msg":"trace[918980357] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:933; }","duration":"2.581697471s","start":"2026-02-24T13:04:00.929785Z","end":"2026-02-24T13:04:03.511483Z","steps":["trace[918980357] 'agreement among raft nodes before linearized reading' (duration: 2.581585218s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:03.511537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:00.929767Z","time spent":"2.581760052s","remote":"127.0.0.1:41492","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":"info","ts":"2026-02-24T13:04:03.511548Z","caller":"traceutil/trace.go:171","msg":"trace[1381280132] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"5.197766003s","start":"2026-02-24T13:03:58.313483Z","end":"2026-02-24T13:04:03.511249Z","steps":["trace[1381280132] 'process raft request' (duration: 5.197243279s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:03.511636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.698563266s","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-02-24T13:04:03.51162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.334587893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T13:04:03.511677Z","caller":"traceutil/trace.go:171","msg":"trace[999113237] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:933; }","duration":"4.698660598s","start":"2026-02-24T13:03:58.813005Z","end":"2026-02-24T13:04:03.511666Z","steps":["trace[999113237] 'agreement among raft nodes before linearized reading' (duration: 4.698599737s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:03.511679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:58.313478Z","time spent":"5.198119012s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:04:03.511699Z","caller":"traceutil/trace.go:171","msg":"trace[776310859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:933; }","duration":"4.334707737s","start":"2026-02-24T13:03:59.176969Z","end":"2026-02-24T13:04:03.511677Z","steps":["trace[776310859] 'agreement among raft nodes before linearized reading' (duration: 4.33448284s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:03.511712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:58.812971Z","time spent":"4.69873328s","remote":"127.0.0.1:41566","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-02-24T13:04:03.511729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.516853024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T13:04:03.511746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:03:59.176952Z","time spent":"4.334785539s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T13:04:03.511785Z","caller":"traceutil/trace.go:171","msg":"trace[1289605820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"1.516948767s","start":"2026-02-24T13:04:01.994822Z","end":"2026-02-24T13:04:03.51177Z","steps":["trace[1289605820] 'agreement among raft nodes before linearized reading' (duration: 1.516845804s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:03.51182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:01.994801Z","time spent":"1.517012459s","remote":"127.0.0.1:41196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T13:04:04.019185Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":662764236348421458,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T13:04:04.520253Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":662764236348421458,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T13:04:05.020405Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":662764236348421458,"retry-timeout":"500ms"} 2026/02/24 13:04:05 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T13:04:05.398451Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.879599162s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T13:04:05.399092Z","caller":"traceutil/trace.go:171","msg":"trace[1492184338] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"1.880318592s","start":"2026-02-24T13:04:03.518748Z","end":"2026-02-24T13:04:05.399067Z","steps":["trace[1492184338] 'process raft request' (duration: 1.880128977s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:05.39925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:03.518734Z","time spent":"1.880436145s","remote":"127.0.0.1:41460","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-02-24T13:04:05.520251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000243898s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-24T13:04:05.520356Z","caller":"traceutil/trace.go:171","msg":"trace[1112973421] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000406523s","start":"2026-02-24T13:04:03.51993Z","end":"2026-02-24T13:04:05.520336Z","steps":["trace[1112973421] 'agreement among raft nodes before linearized reading' (duration: 2.000252149s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:05.520476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:03.519924Z","time spent":"2.000536666s","remote":"127.0.0.1:41208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/24 13:04:05 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T13:04:07.520525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000018982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-24T13:04:07.520591Z","caller":"traceutil/trace.go:171","msg":"trace[1111036448] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000141305s","start":"2026-02-24T13:04:05.520434Z","end":"2026-02-24T13:04:07.520576Z","steps":["trace[1111036448] 'agreement among raft nodes before linearized reading' (duration: 2.000052843s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:07.520636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:05.52041Z","time spent":"2.000213278s","remote":"127.0.0.1:41196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/24 13:04:07 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-24T13:04:07.74027Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.341279444s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T13:04:07.740972Z","caller":"traceutil/trace.go:171","msg":"trace[1149596561] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:981; }","duration":"4.22209156s","start":"2026-02-24T13:04:03.518856Z","end":"2026-02-24T13:04:07.740948Z","steps":["trace[1149596561] 'read index received' (duration: 1.880078756s)","trace[1149596561] 'applied index is now lower than readState.Index' (duration: 2.342011994s)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:07.741103Z","caller":"traceutil/trace.go:171","msg":"trace[1133970122] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"4.218261395s","start":"2026-02-24T13:04:03.522832Z","end":"2026-02-24T13:04:07.741094Z","steps":["trace[1133970122] 'process raft request' (duration: 4.21805095s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:07.741215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:03.522823Z","time spent":"4.218313998s","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7213,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:07.741444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.222583594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.79\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T13:04:07.7415Z","caller":"traceutil/trace.go:171","msg":"trace[1857646859] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"4.221902876s","start":"2026-02-24T13:04:03.519571Z","end":"2026-02-24T13:04:07.741474Z","steps":["trace[1857646859] 'process raft request' (duration: 4.221163336s)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:07.741482Z","caller":"traceutil/trace.go:171","msg":"trace[1056422009] range","detail":"{range_begin:/registry/masterleases/199.19.213.79; range_end:; response_count:1; response_revision:936; }","duration":"4.222634166s","start":"2026-02-24T13:04:03.518838Z","end":"2026-02-24T13:04:07.741472Z","steps":["trace[1056422009] 'agreement among raft nodes before linearized reading' (duration: 4.222516982s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:07.74165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:03.519561Z","time spent":"4.222007418s","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:07.741596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:03.518827Z","time spent":"4.222761519s","remote":"127.0.0.1:41250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.79\" "} {"level":"info","ts":"2026-02-24T13:04:07.796951Z","caller":"traceutil/trace.go:171","msg":"trace[739924603] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"313.415341ms","start":"2026-02-24T13:04:07.483504Z","end":"2026-02-24T13:04:07.79692Z","steps":["trace[739924603] 'process raft request' (duration: 313.264287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:07.797127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:07.483464Z","time spent":"313.574706ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:07.7974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.950662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:04:07.797821Z","caller":"traceutil/trace.go:171","msg":"trace[1952926954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"271.383303ms","start":"2026-02-24T13:04:07.5264Z","end":"2026-02-24T13:04:07.797783Z","steps":["trace[1952926954] 'agreement among raft nodes before linearized reading' (duration: 270.946241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:07.797581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.280841324s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T13:04:07.798207Z","caller":"traceutil/trace.go:171","msg":"trace[1892870493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:937; }","duration":"2.281525302s","start":"2026-02-24T13:04:05.516662Z","end":"2026-02-24T13:04:07.798187Z","steps":["trace[1892870493] 'agreement among raft nodes before linearized reading' (duration: 2.280824413s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:07.798357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:05.516646Z","time spent":"2.281687647s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T13:04:07.797704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.309751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-24T13:04:07.797721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.362980493s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T13:04:07.798943Z","caller":"traceutil/trace.go:171","msg":"trace[2063462006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:937; }","duration":"3.364232397s","start":"2026-02-24T13:04:04.434697Z","end":"2026-02-24T13:04:07.798919Z","steps":["trace[2063462006] 'agreement among raft nodes before linearized reading' (duration: 3.362915271s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:07.799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:04.434682Z","time spent":"3.364303139s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T13:04:07.79906Z","caller":"traceutil/trace.go:171","msg":"trace[297495329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:937; }","duration":"395.206376ms","start":"2026-02-24T13:04:07.403341Z","end":"2026-02-24T13:04:07.798547Z","steps":["trace[297495329] 'agreement among raft nodes before linearized reading' (duration: 394.231519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:07.799125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:07.403319Z","time spent":"395.788942ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T13:04:07.797937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.433866144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T13:04:07.799247Z","caller":"traceutil/trace.go:171","msg":"trace[69736440] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:937; }","duration":"2.435304983s","start":"2026-02-24T13:04:05.363917Z","end":"2026-02-24T13:04:07.799222Z","steps":["trace[69736440] 'agreement among raft nodes before linearized reading' (duration: 2.433638528s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:07.799319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:05.363903Z","time spent":"2.435393665s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T13:04:08.374046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.630712ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-24T13:04:08.374244Z","caller":"traceutil/trace.go:171","msg":"trace[317963936] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"567.07987ms","start":"2026-02-24T13:04:07.807151Z","end":"2026-02-24T13:04:08.37423Z","steps":["trace[317963936] 'process raft request' (duration: 567.05427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:08.374346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:07.807144Z","time spent":"567.135403ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:08.37448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:07.743918Z","time spent":"630.560905ms","remote":"127.0.0.1:41250","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-24T13:04:08.374574Z","caller":"traceutil/trace.go:171","msg":"trace[1545553110] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"571.011408ms","start":"2026-02-24T13:04:07.803533Z","end":"2026-02-24T13:04:08.374544Z","steps":["trace[1545553110] 'process raft request' (duration: 570.591046ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:08.374595Z","caller":"traceutil/trace.go:171","msg":"trace[1858617058] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"577.243328ms","start":"2026-02-24T13:04:07.797317Z","end":"2026-02-24T13:04:08.374561Z","steps":["trace[1858617058] 'read index received' (duration: 384.0423ms)","trace[1858617058] 'applied index is now lower than readState.Index' (duration: 193.198288ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:08.37472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:07.803513Z","time spent":"571.129912ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:08.374774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.84214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:04:08.374806Z","caller":"traceutil/trace.go:171","msg":"trace[1475275349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"575.915912ms","start":"2026-02-24T13:04:07.798882Z","end":"2026-02-24T13:04:08.374798Z","steps":["trace[1475275349] 'agreement among raft nodes before linearized reading' (duration: 575.83555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:08.374807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.692921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.1897306fff517222\" ","response":"range_response_count:1 size:774"} {"level":"warn","ts":"2026-02-24T13:04:08.374839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:07.798862Z","time spent":"575.969564ms","remote":"127.0.0.1:41196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T13:04:08.374875Z","caller":"traceutil/trace.go:171","msg":"trace[556752142] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.1897306fff517222; range_end:; response_count:1; response_revision:939; }","duration":"629.822894ms","start":"2026-02-24T13:04:07.745033Z","end":"2026-02-24T13:04:08.374856Z","steps":["trace[556752142] 'agreement among raft nodes before linearized reading' (duration: 629.631569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:08.37493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:07.745012Z","time spent":"629.906067ms","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.1897306fff517222\" "} {"level":"warn","ts":"2026-02-24T13:04:08.907892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.134453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:04:08.908093Z","caller":"traceutil/trace.go:171","msg":"trace[248811915] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:988; }","duration":"529.890364ms","start":"2026-02-24T13:04:08.378185Z","end":"2026-02-24T13:04:08.908075Z","steps":["trace[248811915] 'read index received' (duration: 186.456803ms)","trace[248811915] 'applied index is now lower than readState.Index' (duration: 343.432331ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:08.908136Z","caller":"traceutil/trace.go:171","msg":"trace[807182028] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"531.742775ms","start":"2026-02-24T13:04:08.376357Z","end":"2026-02-24T13:04:08.9081Z","steps":["trace[807182028] 'process raft request' (duration: 188.299733ms)","trace[807182028] 'compare' (duration: 342.99531ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:08.908336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.179255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T13:04:08.90838Z","caller":"traceutil/trace.go:171","msg":"trace[471039275] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:941; }","duration":"168.268707ms","start":"2026-02-24T13:04:08.7401Z","end":"2026-02-24T13:04:08.908369Z","steps":["trace[471039275] 'agreement among raft nodes before linearized reading' (duration: 168.187525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:08.90842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.228983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7228"} {"level":"info","ts":"2026-02-24T13:04:08.908455Z","caller":"traceutil/trace.go:171","msg":"trace[205755142] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:941; }","duration":"530.285704ms","start":"2026-02-24T13:04:08.378159Z","end":"2026-02-24T13:04:08.908445Z","steps":["trace[205755142] 'agreement among raft nodes before linearized reading' (duration: 530.072209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:08.908494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:08.378147Z","time spent":"530.338606ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7251,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-02-24T13:04:08.908319Z","caller":"traceutil/trace.go:171","msg":"trace[1028153837] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"531.187739ms","start":"2026-02-24T13:04:08.377102Z","end":"2026-02-24T13:04:08.90829Z","steps":["trace[1028153837] 'process raft request' (duration: 530.902671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:08.908663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:08.376338Z","time spent":"532.253288ms","remote":"127.0.0.1:41250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:08.909673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:08.377082Z","time spent":"532.501385ms","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:09.312605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.836882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:04:09.312711Z","caller":"traceutil/trace.go:171","msg":"trace[1241630574] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"396.581793ms","start":"2026-02-24T13:04:08.916112Z","end":"2026-02-24T13:04:09.312694Z","steps":["trace[1241630574] 'read index received' (duration: 231.502644ms)","trace[1241630574] 'applied index is now lower than readState.Index' (duration: 165.077149ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:09.312778Z","caller":"traceutil/trace.go:171","msg":"trace[1658128734] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"396.81505ms","start":"2026-02-24T13:04:08.915952Z","end":"2026-02-24T13:04:09.312767Z","steps":["trace[1658128734] 'process raft request' (duration: 231.71501ms)","trace[1658128734] 'compare' (duration: 164.480863ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:09.312912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:08.915941Z","time spent":"396.928493ms","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:09.313006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.8628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-24T13:04:09.313102Z","caller":"traceutil/trace.go:171","msg":"trace[351587664] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:942; }","duration":"396.982564ms","start":"2026-02-24T13:04:08.916097Z","end":"2026-02-24T13:04:09.313079Z","steps":["trace[351587664] 'agreement among raft nodes before linearized reading' (duration: 396.773659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:09.313169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:08.91609Z","time spent":"397.063706ms","remote":"127.0.0.1:41476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-24T13:04:09.855509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.707312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:04:09.855592Z","caller":"traceutil/trace.go:171","msg":"trace[76334345] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"542.842838ms","start":"2026-02-24T13:04:09.312736Z","end":"2026-02-24T13:04:09.855579Z","steps":["trace[76334345] 'read index received' (duration: 408.907669ms)","trace[76334345] 'applied index is now lower than readState.Index' (duration: 133.934309ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:09.855637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.593988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:04:09.855662Z","caller":"traceutil/trace.go:171","msg":"trace[2118385671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:943; }","duration":"882.65408ms","start":"2026-02-24T13:04:08.973001Z","end":"2026-02-24T13:04:09.855655Z","steps":["trace[2118385671] 'agreement among raft nodes before linearized reading' (duration: 882.611159ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:09.855633Z","caller":"traceutil/trace.go:171","msg":"trace[528809576] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"938.131046ms","start":"2026-02-24T13:04:08.917471Z","end":"2026-02-24T13:04:09.855602Z","steps":["trace[528809576] 'process raft request' (duration: 804.199907ms)","trace[528809576] 'compare' (duration: 133.61831ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:09.855684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:08.972981Z","time spent":"882.697412ms","remote":"127.0.0.1:41208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T13:04:09.855789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:08.917461Z","time spent":"938.241949ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7021,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:04:09.897535Z","caller":"traceutil/trace.go:171","msg":"trace[1079703305] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"580.337333ms","start":"2026-02-24T13:04:09.317143Z","end":"2026-02-24T13:04:09.89748Z","steps":["trace[1079703305] 'process raft request' (duration: 580.141847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:09.897748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:09.317121Z","time spent":"580.540878ms","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":857,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T13:04:10.333485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.338021ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T13:04:10.333599Z","caller":"traceutil/trace.go:171","msg":"trace[217866910] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"436.122073ms","start":"2026-02-24T13:04:09.897458Z","end":"2026-02-24T13:04:10.33358Z","steps":["trace[217866910] 'read index received' (duration: 78.615078ms)","trace[217866910] 'applied index is now lower than readState.Index' (duration: 357.505075ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:10.333823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.382411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.1897306fff517222\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-24T13:04:10.333861Z","caller":"traceutil/trace.go:171","msg":"trace[1363378237] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.1897306fff517222; range_end:; response_count:1; response_revision:944; }","duration":"432.430403ms","start":"2026-02-24T13:04:09.90142Z","end":"2026-02-24T13:04:10.333851Z","steps":["trace[1363378237] 'agreement among raft nodes before linearized reading' (duration: 432.269508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:10.333898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:09.901363Z","time spent":"432.526105ms","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.1897306fff517222\" "} {"level":"warn","ts":"2026-02-24T13:04:10.333882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.274205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4064"} {"level":"info","ts":"2026-02-24T13:04:10.334013Z","caller":"traceutil/trace.go:171","msg":"trace[440788761] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:944; }","duration":"474.46143ms","start":"2026-02-24T13:04:09.859526Z","end":"2026-02-24T13:04:10.333988Z","steps":["trace[440788761] 'agreement among raft nodes before linearized reading' (duration: 474.133391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:10.334086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:09.859493Z","time spent":"474.581654ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4087,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-02-24T13:04:10.521352Z","caller":"traceutil/trace.go:171","msg":"trace[1498917464] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"184.621353ms","start":"2026-02-24T13:04:10.336708Z","end":"2026-02-24T13:04:10.521329Z","steps":["trace[1498917464] 'process raft request' (duration: 184.408797ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:10.870068Z","caller":"traceutil/trace.go:171","msg":"trace[1683950411] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:994; }","duration":"490.848419ms","start":"2026-02-24T13:04:10.379198Z","end":"2026-02-24T13:04:10.870046Z","steps":["trace[1683950411] 'read index received' (duration: 142.03656ms)","trace[1683950411] 'applied index is now lower than readState.Index' (duration: 348.811069ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:10.870159Z","caller":"traceutil/trace.go:171","msg":"trace[1023913102] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"527.52498ms","start":"2026-02-24T13:04:10.342626Z","end":"2026-02-24T13:04:10.870151Z","steps":["trace[1023913102] 'process raft request' (duration: 527.254332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:10.870277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:10.342613Z","time spent":"527.580712ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4343,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:10.870646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.411514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T13:04:10.870761Z","caller":"traceutil/trace.go:171","msg":"trace[856447124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:946; }","duration":"491.598939ms","start":"2026-02-24T13:04:10.379139Z","end":"2026-02-24T13:04:10.870738Z","steps":["trace[856447124] 'agreement among raft nodes before linearized reading' (duration: 491.039183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:10.870813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:10.379116Z","time spent":"491.687291ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T13:04:11.175093Z","caller":"traceutil/trace.go:171","msg":"trace[1144534193] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"649.421459ms","start":"2026-02-24T13:04:10.525643Z","end":"2026-02-24T13:04:11.175064Z","steps":["trace[1144534193] 'process raft request' (duration: 649.108781ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:11.175117Z","caller":"traceutil/trace.go:171","msg":"trace[1153940781] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:996; }","duration":"304.94044ms","start":"2026-02-24T13:04:10.870125Z","end":"2026-02-24T13:04:11.175066Z","steps":["trace[1153940781] 'read index received' (duration: 304.741444ms)","trace[1153940781] 'applied index is now lower than readState.Index' (duration: 197.635µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:11.175261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:10.525615Z","time spent":"649.566774ms","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":846,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T13:04:11.17563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.627474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T13:04:11.175711Z","caller":"traceutil/trace.go:171","msg":"trace[722944989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:948; }","duration":"795.775368ms","start":"2026-02-24T13:04:10.379918Z","end":"2026-02-24T13:04:11.175694Z","steps":["trace[722944989] 'agreement among raft nodes before linearized reading' (duration: 795.286094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:11.175756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:10.379905Z","time spent":"795.842859ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T13:04:11.17626Z","caller":"traceutil/trace.go:171","msg":"trace[508546004] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"400.087549ms","start":"2026-02-24T13:04:10.776156Z","end":"2026-02-24T13:04:11.176244Z","steps":["trace[508546004] 'process raft request' (duration: 398.774663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:11.176463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:10.776127Z","time spent":"400.175751ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:04:11.397287Z","caller":"traceutil/trace.go:171","msg":"trace[942938749] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"222.075846ms","start":"2026-02-24T13:04:11.175186Z","end":"2026-02-24T13:04:11.397262Z","steps":["trace[942938749] 'read index received' (duration: 152.65131ms)","trace[942938749] 'applied index is now lower than readState.Index' (duration: 69.423606ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:11.397427Z","caller":"traceutil/trace.go:171","msg":"trace[1469024890] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"502.823446ms","start":"2026-02-24T13:04:10.894487Z","end":"2026-02-24T13:04:11.397311Z","steps":["trace[1469024890] 'process raft request' (duration: 433.289546ms)","trace[1469024890] 'compare' (duration: 69.305013ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:11.397672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:10.894457Z","time spent":"503.117383ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:11.39785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.123934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6684"} {"level":"info","ts":"2026-02-24T13:04:11.397843Z","caller":"traceutil/trace.go:171","msg":"trace[190675272] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"217.173123ms","start":"2026-02-24T13:04:11.180621Z","end":"2026-02-24T13:04:11.397794Z","steps":["trace[190675272] 'process raft request' (duration: 216.592217ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:11.397923Z","caller":"traceutil/trace.go:171","msg":"trace[976246645] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:950; }","duration":"503.228296ms","start":"2026-02-24T13:04:10.894677Z","end":"2026-02-24T13:04:11.397905Z","steps":["trace[976246645] 'agreement among raft nodes before linearized reading' (duration: 502.703662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:11.397973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:10.894668Z","time spent":"503.292148ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6707,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-02-24T13:04:11.398102Z","caller":"traceutil/trace.go:171","msg":"trace[887808490] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"216.71806ms","start":"2026-02-24T13:04:11.181369Z","end":"2026-02-24T13:04:11.398087Z","steps":["trace[887808490] 'process raft request' (duration: 216.252147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:11.398364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.337634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:04:11.399454Z","caller":"traceutil/trace.go:171","msg":"trace[979756796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"424.507496ms","start":"2026-02-24T13:04:10.97493Z","end":"2026-02-24T13:04:11.399438Z","steps":["trace[979756796] 'agreement among raft nodes before linearized reading' (duration: 423.150729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:11.399596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:10.974909Z","time spent":"424.667941ms","remote":"127.0.0.1:41208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T13:04:11.85035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.437192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:04:11.85099Z","caller":"traceutil/trace.go:171","msg":"trace[1757566489] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"446.518337ms","start":"2026-02-24T13:04:11.404429Z","end":"2026-02-24T13:04:11.850947Z","steps":["trace[1757566489] 'process raft request' (duration: 270.109328ms)","trace[1757566489] 'compare' (duration: 175.277918ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:11.851138Z","caller":"traceutil/trace.go:171","msg":"trace[1218736524] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"443.453514ms","start":"2026-02-24T13:04:11.407588Z","end":"2026-02-24T13:04:11.851041Z","steps":["trace[1218736524] 'process raft request' (duration: 443.151916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:11.851261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:11.407545Z","time spent":"443.661579ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6981,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:11.851479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:11.404413Z","time spent":"446.878177ms","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":813,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T13:04:12.061723Z","caller":"traceutil/trace.go:171","msg":"trace[740371756] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"199.507529ms","start":"2026-02-24T13:04:11.862195Z","end":"2026-02-24T13:04:12.061703Z","steps":["trace[740371756] 'process raft request' (duration: 199.111488ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:13.976935Z","caller":"traceutil/trace.go:171","msg":"trace[857261296] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"119.965317ms","start":"2026-02-24T13:04:13.856946Z","end":"2026-02-24T13:04:13.976912Z","steps":["trace[857261296] 'process raft request' (duration: 57.715407ms)","trace[857261296] 'compare' (duration: 62.130387ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:14.904257Z","caller":"traceutil/trace.go:171","msg":"trace[844213504] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"212.735791ms","start":"2026-02-24T13:04:14.691497Z","end":"2026-02-24T13:04:14.904233Z","steps":["trace[844213504] 'read index received' (duration: 212.585057ms)","trace[844213504] 'applied index is now lower than readState.Index' (duration: 149.764µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:14.904493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.973818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4358"} {"level":"info","ts":"2026-02-24T13:04:14.904533Z","caller":"traceutil/trace.go:171","msg":"trace[40467830] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:962; }","duration":"213.06369ms","start":"2026-02-24T13:04:14.691459Z","end":"2026-02-24T13:04:14.904523Z","steps":["trace[40467830] 'agreement among raft nodes before linearized reading' (duration: 212.869735ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:14.90485Z","caller":"traceutil/trace.go:171","msg":"trace[1622431055] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"218.744395ms","start":"2026-02-24T13:04:14.68607Z","end":"2026-02-24T13:04:14.904815Z","steps":["trace[1622431055] 'process raft request' (duration: 218.034276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:15.213614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.421486ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:04:15.21371Z","caller":"traceutil/trace.go:171","msg":"trace[336593934] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:962; }","duration":"147.530699ms","start":"2026-02-24T13:04:15.066166Z","end":"2026-02-24T13:04:15.213697Z","steps":["trace[336593934] 'range keys from in-memory index tree' (duration: 147.408906ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:15.247105Z","caller":"traceutil/trace.go:171","msg":"trace[1502666786] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"271.2476ms","start":"2026-02-24T13:04:14.97584Z","end":"2026-02-24T13:04:15.247088Z","steps":["trace[1502666786] 'read index received' (duration: 271.105226ms)","trace[1502666786] 'applied index is now lower than readState.Index' (duration: 141.803µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:15.247376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.975951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T13:04:15.247434Z","caller":"traceutil/trace.go:171","msg":"trace[409599517] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:963; }","duration":"171.077314ms","start":"2026-02-24T13:04:15.076345Z","end":"2026-02-24T13:04:15.247422Z","steps":["trace[409599517] 'agreement among raft nodes before linearized reading' (duration: 170.93767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:15.2476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.769123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:04:15.247636Z","caller":"traceutil/trace.go:171","msg":"trace[1025968947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"271.841315ms","start":"2026-02-24T13:04:14.975789Z","end":"2026-02-24T13:04:15.24763Z","steps":["trace[1025968947] 'agreement among raft nodes before linearized reading' (duration: 271.435514ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:15.248246Z","caller":"traceutil/trace.go:171","msg":"trace[1843500987] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"334.60352ms","start":"2026-02-24T13:04:14.913614Z","end":"2026-02-24T13:04:15.248217Z","steps":["trace[1843500987] 'process raft request' (duration: 333.379837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:15.249196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:14.913593Z","time spent":"335.443213ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:04:15.403432Z","caller":"traceutil/trace.go:171","msg":"trace[382051232] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"128.364896ms","start":"2026-02-24T13:04:15.275024Z","end":"2026-02-24T13:04:15.403389Z","steps":["trace[382051232] 'process raft request' (duration: 127.190564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:15.725418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.683816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:04:15.72553Z","caller":"traceutil/trace.go:171","msg":"trace[426793659] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1016; }","duration":"305.314129ms","start":"2026-02-24T13:04:15.420197Z","end":"2026-02-24T13:04:15.725512Z","steps":["trace[426793659] 'read index received' (duration: 112.871583ms)","trace[426793659] 'applied index is now lower than readState.Index' (duration: 192.440816ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:15.725634Z","caller":"traceutil/trace.go:171","msg":"trace[2068507825] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"314.297374ms","start":"2026-02-24T13:04:15.411322Z","end":"2026-02-24T13:04:15.72562Z","steps":["trace[2068507825] 'process raft request' (duration: 122.265959ms)","trace[2068507825] 'compare' (duration: 191.554962ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:15.725699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:15.411303Z","time spent":"314.362727ms","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:15.72588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.335269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T13:04:15.725947Z","caller":"traceutil/trace.go:171","msg":"trace[613729100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:966; }","duration":"298.437292ms","start":"2026-02-24T13:04:15.427493Z","end":"2026-02-24T13:04:15.72593Z","steps":["trace[613729100] 'agreement among raft nodes before linearized reading' (duration: 298.167864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:15.726065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.485744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T13:04:15.726105Z","caller":"traceutil/trace.go:171","msg":"trace[1883396363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"286.526286ms","start":"2026-02-24T13:04:15.439568Z","end":"2026-02-24T13:04:15.726094Z","steps":["trace[1883396363] 'agreement among raft nodes before linearized reading' (duration: 286.424704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:15.726236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.037849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T13:04:15.726265Z","caller":"traceutil/trace.go:171","msg":"trace[1119464191] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"306.107551ms","start":"2026-02-24T13:04:15.420149Z","end":"2026-02-24T13:04:15.726257Z","steps":["trace[1119464191] 'agreement among raft nodes before linearized reading' (duration: 306.029579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:15.72629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:15.420128Z","time spent":"306.155723ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-24T13:04:15.9999Z","caller":"traceutil/trace.go:171","msg":"trace[1691537403] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"267.664092ms","start":"2026-02-24T13:04:15.732212Z","end":"2026-02-24T13:04:15.999876Z","steps":["trace[1691537403] 'process raft request' (duration: 267.416145ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:16.000108Z","caller":"traceutil/trace.go:171","msg":"trace[936192844] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1017; }","duration":"255.240382ms","start":"2026-02-24T13:04:15.744827Z","end":"2026-02-24T13:04:16.000067Z","steps":["trace[936192844] 'read index received' (duration: 49.07057ms)","trace[936192844] 'applied index is now lower than readState.Index' (duration: 206.166752ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:16.000284Z","caller":"traceutil/trace.go:171","msg":"trace[1396372771] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"262.936923ms","start":"2026-02-24T13:04:15.737334Z","end":"2026-02-24T13:04:16.000271Z","steps":["trace[1396372771] 'process raft request' (duration: 262.47511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:16.000478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.631954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:1 size:3017"} {"level":"info","ts":"2026-02-24T13:04:16.000541Z","caller":"traceutil/trace.go:171","msg":"trace[1344474690] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:1; response_revision:969; }","duration":"255.731866ms","start":"2026-02-24T13:04:15.744791Z","end":"2026-02-24T13:04:16.000523Z","steps":["trace[1344474690] 'agreement among raft nodes before linearized reading' (duration: 255.610592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:17.476695Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":662764236348421543,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T13:04:17.531676Z","caller":"traceutil/trace.go:171","msg":"trace[985323706] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"556.069909ms","start":"2026-02-24T13:04:16.975581Z","end":"2026-02-24T13:04:17.531651Z","steps":["trace[985323706] 'read index received' (duration: 555.631987ms)","trace[985323706] 'applied index is now lower than readState.Index' (duration: 436.772µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:17.531732Z","caller":"traceutil/trace.go:171","msg":"trace[605173675] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"813.079261ms","start":"2026-02-24T13:04:16.718624Z","end":"2026-02-24T13:04:17.531703Z","steps":["trace[605173675] 'process raft request' (duration: 812.856445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:17.531819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.215654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:04:17.531858Z","caller":"traceutil/trace.go:171","msg":"trace[794421666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"556.345728ms","start":"2026-02-24T13:04:16.975503Z","end":"2026-02-24T13:04:17.531848Z","steps":["trace[794421666] 'agreement among raft nodes before linearized reading' (duration: 556.248745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:17.531897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:16.975471Z","time spent":"556.418659ms","remote":"127.0.0.1:41196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T13:04:17.531906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:16.71859Z","time spent":"813.213764ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6774,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:18.476118Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":662764236348421547,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T13:04:18.64525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.238017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T13:04:18.645579Z","caller":"traceutil/trace.go:171","msg":"trace[781581640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:970; }","duration":"968.574516ms","start":"2026-02-24T13:04:17.676983Z","end":"2026-02-24T13:04:18.645558Z","steps":["trace[781581640] 'range keys from in-memory index tree' (duration: 968.099144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:18.64571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:17.676924Z","time spent":"968.778932ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T13:04:18.645612Z","caller":"traceutil/trace.go:171","msg":"trace[2129622068] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1021; }","duration":"670.137285ms","start":"2026-02-24T13:04:17.975466Z","end":"2026-02-24T13:04:18.645604Z","steps":["trace[2129622068] 'read index received' (duration: 602.150999ms)","trace[2129622068] 'applied index is now lower than readState.Index' (duration: 67.985496ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:18.645672Z","caller":"traceutil/trace.go:171","msg":"trace[1782392376] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"824.330916ms","start":"2026-02-24T13:04:17.82133Z","end":"2026-02-24T13:04:18.645661Z","steps":["trace[1782392376] 'process raft request' (duration: 756.315339ms)","trace[1782392376] 'compare' (duration: 67.882674ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:18.645976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.959336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T13:04:18.646046Z","caller":"traceutil/trace.go:171","msg":"trace[1631916271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:971; }","duration":"324.070208ms","start":"2026-02-24T13:04:18.321969Z","end":"2026-02-24T13:04:18.646039Z","steps":["trace[1631916271] 'agreement among raft nodes before linearized reading' (duration: 323.917004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:18.646109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:18.321943Z","time spent":"324.159201ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T13:04:18.646124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.9807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-24T13:04:18.64595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:17.82128Z","time spent":"824.552662ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:04:18.646184Z","caller":"traceutil/trace.go:171","msg":"trace[426148153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:971; }","duration":"640.131265ms","start":"2026-02-24T13:04:18.006037Z","end":"2026-02-24T13:04:18.646168Z","steps":["trace[426148153] 'agreement among raft nodes before linearized reading' (duration: 639.881618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:18.646227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:18.006008Z","time spent":"640.209817ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T13:04:18.645663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.218287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T13:04:18.64536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.469358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T13:04:18.646438Z","caller":"traceutil/trace.go:171","msg":"trace[1000795081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:971; }","duration":"670.973398ms","start":"2026-02-24T13:04:17.975439Z","end":"2026-02-24T13:04:18.646413Z","steps":["trace[1000795081] 'agreement among raft nodes before linearized reading' (duration: 670.188316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:18.646725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:17.975346Z","time spent":"671.371318ms","remote":"127.0.0.1:41208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T13:04:18.646615Z","caller":"traceutil/trace.go:171","msg":"trace[1032044066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:970; }","duration":"848.752713ms","start":"2026-02-24T13:04:17.797824Z","end":"2026-02-24T13:04:18.646576Z","steps":["trace[1032044066] 'range keys from in-memory index tree' (duration: 847.336515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:18.646961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:17.797791Z","time spent":"849.115463ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T13:04:19.972601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186888325s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:04:19.972784Z","caller":"traceutil/trace.go:171","msg":"trace[501547] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"1.320715308s","start":"2026-02-24T13:04:18.652019Z","end":"2026-02-24T13:04:19.972735Z","steps":["trace[501547] 'process raft request' (duration: 133.585017ms)","trace[501547] 'compare' (duration: 1.186265158s)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:19.972921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:18.652006Z","time spent":"1.320838891s","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:19.972948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.367699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T13:04:19.972985Z","caller":"traceutil/trace.go:171","msg":"trace[1901774472] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:973; }","duration":"934.449251ms","start":"2026-02-24T13:04:19.038524Z","end":"2026-02-24T13:04:19.972973Z","steps":["trace[1901774472] 'agreement among raft nodes before linearized reading' (duration: 934.295547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:19.973022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:19.038501Z","time spent":"934.511823ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-24T13:04:19.972789Z","caller":"traceutil/trace.go:171","msg":"trace[835904931] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1023; }","duration":"934.136943ms","start":"2026-02-24T13:04:19.038571Z","end":"2026-02-24T13:04:19.972708Z","steps":["trace[835904931] 'read index received' (duration: 40.961µs)","trace[835904931] 'applied index is now lower than readState.Index' (duration: 934.094652ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:19.973226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.264806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T13:04:19.973281Z","caller":"traceutil/trace.go:171","msg":"trace[1980543970] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:973; }","duration":"218.332548ms","start":"2026-02-24T13:04:19.754912Z","end":"2026-02-24T13:04:19.973245Z","steps":["trace[1980543970] 'agreement among raft nodes before linearized reading' (duration: 218.284667ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:21.572508Z","caller":"traceutil/trace.go:171","msg":"trace[350917727] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"241.451996ms","start":"2026-02-24T13:04:21.331034Z","end":"2026-02-24T13:04:21.572486Z","steps":["trace[350917727] 'process raft request' (duration: 241.314322ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:22.112601Z","caller":"traceutil/trace.go:171","msg":"trace[1234848826] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"129.450124ms","start":"2026-02-24T13:04:21.983127Z","end":"2026-02-24T13:04:22.112577Z","steps":["trace[1234848826] 'process raft request' (duration: 129.304871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:22.378022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.359056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T13:04:22.378083Z","caller":"traceutil/trace.go:171","msg":"trace[1549023901] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:976; }","duration":"110.464659ms","start":"2026-02-24T13:04:22.267605Z","end":"2026-02-24T13:04:22.37807Z","steps":["trace[1549023901] 'range keys from in-memory index tree' (duration: 110.210232ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:23.316252Z","caller":"traceutil/trace.go:171","msg":"trace[971826781] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"332.792066ms","start":"2026-02-24T13:04:22.983436Z","end":"2026-02-24T13:04:23.316228Z","steps":["trace[971826781] 'read index received' (duration: 332.606981ms)","trace[971826781] 'applied index is now lower than readState.Index' (duration: 183.945µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:23.316333Z","caller":"traceutil/trace.go:171","msg":"trace[1902277250] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"486.675564ms","start":"2026-02-24T13:04:22.829619Z","end":"2026-02-24T13:04:23.316295Z","steps":["trace[1902277250] 'process raft request' (duration: 486.446558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:23.316431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.979291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:04:23.316556Z","caller":"traceutil/trace.go:171","msg":"trace[1060434921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:977; }","duration":"333.151396ms","start":"2026-02-24T13:04:22.983392Z","end":"2026-02-24T13:04:23.316544Z","steps":["trace[1060434921] 'agreement among raft nodes before linearized reading' (duration: 332.982411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:23.316601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:22.983357Z","time spent":"333.234368ms","remote":"127.0.0.1:41196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T13:04:23.316712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:22.829597Z","time spent":"486.824667ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:23.725559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.032975ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T13:04:23.725669Z","caller":"traceutil/trace.go:171","msg":"trace[858111720] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"205.342335ms","start":"2026-02-24T13:04:23.520308Z","end":"2026-02-24T13:04:23.72565Z","steps":["trace[858111720] 'read index received' (duration: 93.392µs)","trace[858111720] 'applied index is now lower than readState.Index' (duration: 205.247203ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:23.725877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.560762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.79\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T13:04:23.725952Z","caller":"traceutil/trace.go:171","msg":"trace[1886013757] range","detail":"{range_begin:/registry/masterleases/199.19.213.79; range_end:; response_count:1; response_revision:977; }","duration":"205.704665ms","start":"2026-02-24T13:04:23.520232Z","end":"2026-02-24T13:04:23.725937Z","steps":["trace[1886013757] 'agreement among raft nodes before linearized reading' (duration: 205.466759ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:23.881027Z","caller":"traceutil/trace.go:171","msg":"trace[1625530900] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1029; }","duration":"102.76719ms","start":"2026-02-24T13:04:23.778227Z","end":"2026-02-24T13:04:23.880994Z","steps":["trace[1625530900] 'read index received' (duration: 102.634186ms)","trace[1625530900] 'applied index is now lower than readState.Index' (duration: 131.254µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:23.881229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.983616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:04:23.881359Z","caller":"traceutil/trace.go:171","msg":"trace[545457275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:977; }","duration":"103.174461ms","start":"2026-02-24T13:04:23.778159Z","end":"2026-02-24T13:04:23.881334Z","steps":["trace[545457275] 'agreement among raft nodes before linearized reading' (duration: 102.964926ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:24.267211Z","caller":"traceutil/trace.go:171","msg":"trace[202952417] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"145.210913ms","start":"2026-02-24T13:04:24.12198Z","end":"2026-02-24T13:04:24.267191Z","steps":["trace[202952417] 'process raft request' (duration: 145.065019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:24.523958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.030212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:04:24.524066Z","caller":"traceutil/trace.go:171","msg":"trace[1813638173] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"193.447692ms","start":"2026-02-24T13:04:24.330598Z","end":"2026-02-24T13:04:24.524045Z","steps":["trace[1813638173] 'process raft request' (duration: 60.260876ms)","trace[1813638173] 'compare' (duration: 132.906059ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:25.744336Z","caller":"traceutil/trace.go:171","msg":"trace[879468043] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"414.216746ms","start":"2026-02-24T13:04:25.330097Z","end":"2026-02-24T13:04:25.744313Z","steps":["trace[879468043] 'process raft request' (duration: 414.053842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:25.74462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.944197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T13:04:25.744651Z","caller":"traceutil/trace.go:171","msg":"trace[1364059776] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1034; }","duration":"347.637499ms","start":"2026-02-24T13:04:25.396651Z","end":"2026-02-24T13:04:25.744289Z","steps":["trace[1364059776] 'read index received' (duration: 347.626978ms)","trace[1364059776] 'applied index is now lower than readState.Index' (duration: 8.951µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:25.744706Z","caller":"traceutil/trace.go:171","msg":"trace[395504814] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:981; }","duration":"348.112351ms","start":"2026-02-24T13:04:25.396575Z","end":"2026-02-24T13:04:25.744687Z","steps":["trace[395504814] 'agreement among raft nodes before linearized reading' (duration: 347.870435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:25.744765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:25.396551Z","time spent":"348.203974ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T13:04:25.745028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.990933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4370"} {"level":"info","ts":"2026-02-24T13:04:25.745157Z","caller":"traceutil/trace.go:171","msg":"trace[1297530091] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:981; }","duration":"199.105346ms","start":"2026-02-24T13:04:25.545981Z","end":"2026-02-24T13:04:25.745086Z","steps":["trace[1297530091] 'agreement among raft nodes before linearized reading' (duration: 198.85597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:25.744547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:25.330081Z","time spent":"414.33459ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:04:26.177777Z","caller":"traceutil/trace.go:171","msg":"trace[299698365] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"423.803237ms","start":"2026-02-24T13:04:25.753953Z","end":"2026-02-24T13:04:26.177756Z","steps":["trace[299698365] 'process raft request' (duration: 423.531549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:26.17795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:25.753927Z","time spent":"423.9252ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:04:26.177709Z","caller":"traceutil/trace.go:171","msg":"trace[812899515] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"195.287393ms","start":"2026-02-24T13:04:25.982391Z","end":"2026-02-24T13:04:26.177678Z","steps":["trace[812899515] 'read index received' (duration: 195.078777ms)","trace[812899515] 'applied index is now lower than readState.Index' (duration: 207.616µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:26.178106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.709214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:04:26.178155Z","caller":"traceutil/trace.go:171","msg":"trace[436353030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:982; }","duration":"195.797876ms","start":"2026-02-24T13:04:25.982345Z","end":"2026-02-24T13:04:26.178143Z","steps":["trace[436353030] 'agreement among raft nodes before linearized reading' (duration: 195.640102ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:26.593727Z","caller":"traceutil/trace.go:171","msg":"trace[713467886] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"320.883291ms","start":"2026-02-24T13:04:26.272818Z","end":"2026-02-24T13:04:26.593702Z","steps":["trace[713467886] 'read index received' (duration: 320.669015ms)","trace[713467886] 'applied index is now lower than readState.Index' (duration: 212.976µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:26.593943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.104077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T13:04:26.593984Z","caller":"traceutil/trace.go:171","msg":"trace[673636291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:983; }","duration":"321.21398ms","start":"2026-02-24T13:04:26.272759Z","end":"2026-02-24T13:04:26.593973Z","steps":["trace[673636291] 'agreement among raft nodes before linearized reading' (duration: 321.038476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:26.594021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:26.272735Z","time spent":"321.278222ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T13:04:26.593922Z","caller":"traceutil/trace.go:171","msg":"trace[2091087214] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"404.361188ms","start":"2026-02-24T13:04:26.189485Z","end":"2026-02-24T13:04:26.593847Z","steps":["trace[2091087214] 'process raft request' (duration: 404.06336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:26.594311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:26.189474Z","time spent":"404.688367ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4163,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:26.779826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.326083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:04:26.780104Z","caller":"traceutil/trace.go:171","msg":"trace[597500207] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"180.716167ms","start":"2026-02-24T13:04:26.599369Z","end":"2026-02-24T13:04:26.780085Z","steps":["trace[597500207] 'process raft request' (duration: 48.034234ms)","trace[597500207] 'compare' (duration: 132.012254ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:26.780163Z","caller":"traceutil/trace.go:171","msg":"trace[773151013] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1036; }","duration":"153.97229ms","start":"2026-02-24T13:04:26.626173Z","end":"2026-02-24T13:04:26.780146Z","steps":["trace[773151013] 'read index received' (duration: 21.36728ms)","trace[773151013] 'applied index is now lower than readState.Index' (duration: 132.60366ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:26.780384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.197467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T13:04:26.780422Z","caller":"traceutil/trace.go:171","msg":"trace[1937450966] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:985; }","duration":"154.273018ms","start":"2026-02-24T13:04:26.626139Z","end":"2026-02-24T13:04:26.780412Z","steps":["trace[1937450966] 'agreement among raft nodes before linearized reading' (duration: 154.091804ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:26.780721Z","caller":"traceutil/trace.go:171","msg":"trace[1551489445] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"181.133738ms","start":"2026-02-24T13:04:26.59957Z","end":"2026-02-24T13:04:26.780704Z","steps":["trace[1551489445] 'process raft request' (duration: 180.377188ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:27.116226Z","caller":"traceutil/trace.go:171","msg":"trace[803751843] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"225.781721ms","start":"2026-02-24T13:04:26.890422Z","end":"2026-02-24T13:04:27.116203Z","steps":["trace[803751843] 'read index received' (duration: 152.44779ms)","trace[803751843] 'applied index is now lower than readState.Index' (duration: 73.332891ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:27.116348Z","caller":"traceutil/trace.go:171","msg":"trace[1980594602] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"231.400684ms","start":"2026-02-24T13:04:26.884936Z","end":"2026-02-24T13:04:27.116337Z","steps":["trace[1980594602] 'process raft request' (duration: 157.931279ms)","trace[1980594602] 'compare' (duration: 73.241518ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:27.116823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.972882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T13:04:27.116876Z","caller":"traceutil/trace.go:171","msg":"trace[1575270899] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:986; }","duration":"157.081025ms","start":"2026-02-24T13:04:26.959783Z","end":"2026-02-24T13:04:27.116864Z","steps":["trace[1575270899] 'agreement among raft nodes before linearized reading' (duration: 156.956771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:27.117398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.934612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:7008"} {"level":"info","ts":"2026-02-24T13:04:27.117632Z","caller":"traceutil/trace.go:171","msg":"trace[641210148] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:986; }","duration":"227.145467ms","start":"2026-02-24T13:04:26.890405Z","end":"2026-02-24T13:04:27.11755Z","steps":["trace[641210148] 'agreement among raft nodes before linearized reading' (duration: 226.642724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:27.423984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.581923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:04:27.424303Z","caller":"traceutil/trace.go:171","msg":"trace[347878013] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"303.002687ms","start":"2026-02-24T13:04:27.121226Z","end":"2026-02-24T13:04:27.424228Z","steps":["trace[347878013] 'process raft request' (duration: 192.059135ms)","trace[347878013] 'compare' (duration: 110.364306ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:27.424549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:27.121196Z","time spent":"303.259294ms","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T13:04:27.70156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.91209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:04:27.701765Z","caller":"traceutil/trace.go:171","msg":"trace[370386271] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"576.188735ms","start":"2026-02-24T13:04:27.125547Z","end":"2026-02-24T13:04:27.701735Z","steps":["trace[370386271] 'process raft request' (duration: 418.941515ms)","trace[370386271] 'compare' (duration: 156.542521ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:27.701921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:27.125538Z","time spent":"576.293667ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6993,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:04:27.883014Z","caller":"traceutil/trace.go:171","msg":"trace[1854315559] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"134.44144ms","start":"2026-02-24T13:04:27.748526Z","end":"2026-02-24T13:04:27.882968Z","steps":["trace[1854315559] 'read index received' (duration: 134.226924ms)","trace[1854315559] 'applied index is now lower than readState.Index' (duration: 212.686µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:27.88328Z","caller":"traceutil/trace.go:171","msg":"trace[587630503] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"172.589576ms","start":"2026-02-24T13:04:27.710668Z","end":"2026-02-24T13:04:27.883257Z","steps":["trace[587630503] 'process raft request' (duration: 172.01238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:27.883445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.875232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T13:04:27.88349Z","caller":"traceutil/trace.go:171","msg":"trace[1227508534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:989; }","duration":"135.004995ms","start":"2026-02-24T13:04:27.748472Z","end":"2026-02-24T13:04:27.883477Z","steps":["trace[1227508534] 'agreement among raft nodes before linearized reading' (duration: 134.648755ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:29.201948Z","caller":"traceutil/trace.go:171","msg":"trace[2007668915] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"410.67014ms","start":"2026-02-24T13:04:28.791259Z","end":"2026-02-24T13:04:29.201929Z","steps":["trace[2007668915] 'process raft request' (duration: 410.527456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:29.202072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:28.791244Z","time spent":"410.763263ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:04:29.636725Z","caller":"traceutil/trace.go:171","msg":"trace[722465808] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"789.353312ms","start":"2026-02-24T13:04:28.847318Z","end":"2026-02-24T13:04:29.636671Z","steps":["trace[722465808] 'process raft request' (duration: 705.133185ms)","trace[722465808] 'compare' (duration: 83.798465ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:29.636956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:28.847247Z","time spent":"789.582718ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:29.766744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.123013ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T13:04:29.766906Z","caller":"traceutil/trace.go:171","msg":"trace[304665711] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1044; }","duration":"495.040491ms","start":"2026-02-24T13:04:29.271841Z","end":"2026-02-24T13:04:29.766881Z","steps":["trace[304665711] 'read index received' (duration: 280.534707ms)","trace[304665711] 'applied index is now lower than readState.Index' (duration: 214.503914ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:29.767167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.303398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T13:04:29.767258Z","caller":"traceutil/trace.go:171","msg":"trace[1466084613] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:992; }","duration":"495.447762ms","start":"2026-02-24T13:04:29.271791Z","end":"2026-02-24T13:04:29.767239Z","steps":["trace[1466084613] 'agreement among raft nodes before linearized reading' (duration: 495.174335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:29.767312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:29.271773Z","time spent":"495.530365ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T13:04:29.767204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.407397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T13:04:29.767412Z","caller":"traceutil/trace.go:171","msg":"trace[1641736481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:992; }","duration":"247.635654ms","start":"2026-02-24T13:04:29.519756Z","end":"2026-02-24T13:04:29.767392Z","steps":["trace[1641736481] 'agreement among raft nodes before linearized reading' (duration: 247.251504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:30.080182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.598886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:04:30.080267Z","caller":"traceutil/trace.go:171","msg":"trace[467951864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:993; }","duration":"105.698079ms","start":"2026-02-24T13:04:29.974553Z","end":"2026-02-24T13:04:30.080251Z","steps":["trace[467951864] 'range keys from in-memory index tree' (duration: 105.520534ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:30.275458Z","caller":"traceutil/trace.go:171","msg":"trace[795339246] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1047; }","duration":"194.426429ms","start":"2026-02-24T13:04:30.081Z","end":"2026-02-24T13:04:30.275426Z","steps":["trace[795339246] 'read index received' (duration: 194.09831ms)","trace[795339246] 'applied index is now lower than readState.Index' (duration: 326.499µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:30.27553Z","caller":"traceutil/trace.go:171","msg":"trace[1416308581] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"201.349947ms","start":"2026-02-24T13:04:30.074147Z","end":"2026-02-24T13:04:30.275497Z","steps":["trace[1416308581] 'process raft request' (duration: 201.09198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:30.275613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.580773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:04:30.275671Z","caller":"traceutil/trace.go:171","msg":"trace[2138512644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:994; }","duration":"194.686827ms","start":"2026-02-24T13:04:30.080965Z","end":"2026-02-24T13:04:30.275652Z","steps":["trace[2138512644] 'agreement among raft nodes before linearized reading' (duration: 194.582194ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:31.687103Z","caller":"traceutil/trace.go:171","msg":"trace[246750206] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"141.102171ms","start":"2026-02-24T13:04:31.545972Z","end":"2026-02-24T13:04:31.687074Z","steps":["trace[246750206] 'process raft request' (duration: 140.920116ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:31.859375Z","caller":"traceutil/trace.go:171","msg":"trace[1133912694] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"168.425763ms","start":"2026-02-24T13:04:31.690924Z","end":"2026-02-24T13:04:31.85935Z","steps":["trace[1133912694] 'process raft request' (duration: 168.347361ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:31.859394Z","caller":"traceutil/trace.go:171","msg":"trace[1504010848] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"170.1368ms","start":"2026-02-24T13:04:31.689211Z","end":"2026-02-24T13:04:31.859348Z","steps":["trace[1504010848] 'process raft request' (duration: 97.94109ms)","trace[1504010848] 'compare' (duration: 71.954993ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:31.859729Z","caller":"traceutil/trace.go:171","msg":"trace[536662067] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1050; }","duration":"169.350198ms","start":"2026-02-24T13:04:31.690347Z","end":"2026-02-24T13:04:31.859697Z","steps":["trace[536662067] 'read index received' (duration: 96.818669ms)","trace[536662067] 'applied index is now lower than readState.Index' (duration: 72.528299ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:31.859959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.753132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T13:04:31.85998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.593535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T13:04:31.860034Z","caller":"traceutil/trace.go:171","msg":"trace[608376509] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:998; }","duration":"169.680717ms","start":"2026-02-24T13:04:31.69034Z","end":"2026-02-24T13:04:31.86002Z","steps":["trace[608376509] 'agreement among raft nodes before linearized reading' (duration: 169.490522ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:31.860034Z","caller":"traceutil/trace.go:171","msg":"trace[1940004649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"168.870135ms","start":"2026-02-24T13:04:31.691144Z","end":"2026-02-24T13:04:31.860014Z","steps":["trace[1940004649] 'agreement among raft nodes before linearized reading' (duration: 168.722801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:32.193287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.045088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:04:32.193418Z","caller":"traceutil/trace.go:171","msg":"trace[1237990890] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1052; }","duration":"320.683157ms","start":"2026-02-24T13:04:31.872717Z","end":"2026-02-24T13:04:32.1934Z","steps":["trace[1237990890] 'read index received' (duration: 196.350161ms)","trace[1237990890] 'applied index is now lower than readState.Index' (duration: 124.331606ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:32.193448Z","caller":"traceutil/trace.go:171","msg":"trace[1080436449] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"329.175417ms","start":"2026-02-24T13:04:31.864241Z","end":"2026-02-24T13:04:32.193416Z","steps":["trace[1080436449] 'process raft request' (duration: 204.905303ms)","trace[1080436449] 'compare' (duration: 123.883044ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:32.193528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.807361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-24T13:04:32.193569Z","caller":"traceutil/trace.go:171","msg":"trace[656352044] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:999; }","duration":"320.873182ms","start":"2026-02-24T13:04:31.872686Z","end":"2026-02-24T13:04:32.193559Z","steps":["trace[656352044] 'agreement among raft nodes before linearized reading' (duration: 320.7953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:32.19361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:31.872671Z","time spent":"320.931644ms","remote":"127.0.0.1:41408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-02-24T13:04:32.193663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:31.864224Z","time spent":"329.348252ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:32.193914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.668701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:04:32.194096Z","caller":"traceutil/trace.go:171","msg":"trace[926286094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:999; }","duration":"220.862396ms","start":"2026-02-24T13:04:31.973179Z","end":"2026-02-24T13:04:32.194041Z","steps":["trace[926286094] 'agreement among raft nodes before linearized reading' (duration: 220.609479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:32.194224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.69642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T13:04:32.194275Z","caller":"traceutil/trace.go:171","msg":"trace[1710912671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:999; }","duration":"242.787112ms","start":"2026-02-24T13:04:31.951475Z","end":"2026-02-24T13:04:32.194262Z","steps":["trace[1710912671] 'agreement among raft nodes before linearized reading' (duration: 242.613938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:32.194551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.15078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T13:04:32.194655Z","caller":"traceutil/trace.go:171","msg":"trace[376298354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:999; }","duration":"226.748426ms","start":"2026-02-24T13:04:31.967883Z","end":"2026-02-24T13:04:32.194631Z","steps":["trace[376298354] 'agreement among raft nodes before linearized reading' (duration: 225.936544ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:32.510387Z","caller":"traceutil/trace.go:171","msg":"trace[452548800] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1053; }","duration":"309.122793ms","start":"2026-02-24T13:04:32.201242Z","end":"2026-02-24T13:04:32.510364Z","steps":["trace[452548800] 'read index received' (duration: 75.658605ms)","trace[452548800] 'applied index is now lower than readState.Index' (duration: 233.463138ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:32.510635Z","caller":"traceutil/trace.go:171","msg":"trace[163309041] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"311.59835ms","start":"2026-02-24T13:04:32.199008Z","end":"2026-02-24T13:04:32.510606Z","steps":["trace[163309041] 'process raft request' (duration: 311.23293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:32.510765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:32.198991Z","time spent":"311.696122ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:32.510782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.508244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-24T13:04:32.510934Z","caller":"traceutil/trace.go:171","msg":"trace[538962847] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:1001; }","duration":"309.659157ms","start":"2026-02-24T13:04:32.201217Z","end":"2026-02-24T13:04:32.510876Z","steps":["trace[538962847] 'agreement among raft nodes before linearized reading' (duration: 309.39921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:32.511049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:32.201209Z","time spent":"309.77595ms","remote":"127.0.0.1:41408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-24T13:04:32.707234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.003548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:04:32.707372Z","caller":"traceutil/trace.go:171","msg":"trace[930916021] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1055; }","duration":"196.899716ms","start":"2026-02-24T13:04:32.510453Z","end":"2026-02-24T13:04:32.707353Z","steps":["trace[930916021] 'read index received' (duration: 44.700554ms)","trace[930916021] 'applied index is now lower than readState.Index' (duration: 152.197852ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:32.707449Z","caller":"traceutil/trace.go:171","msg":"trace[1438344494] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"426.623294ms","start":"2026-02-24T13:04:32.280777Z","end":"2026-02-24T13:04:32.707401Z","steps":["trace[1438344494] 'process raft request' (duration: 274.368519ms)","trace[1438344494] 'compare' (duration: 151.832383ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:32.707603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:32.280757Z","time spent":"426.778927ms","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T13:04:32.707743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.029691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-24T13:04:32.707842Z","caller":"traceutil/trace.go:171","msg":"trace[1295643076] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:1002; }","duration":"193.175165ms","start":"2026-02-24T13:04:32.514641Z","end":"2026-02-24T13:04:32.707816Z","steps":["trace[1295643076] 'agreement among raft nodes before linearized reading' (duration: 192.972899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:32.709455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.632158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T13:04:32.709517Z","caller":"traceutil/trace.go:171","msg":"trace[637184537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1002; }","duration":"428.725341ms","start":"2026-02-24T13:04:32.280777Z","end":"2026-02-24T13:04:32.709502Z","steps":["trace[637184537] 'agreement among raft nodes before linearized reading' (duration: 426.678915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:32.707696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.673305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T13:04:32.709553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:32.280765Z","time spent":"428.778612ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T13:04:32.709576Z","caller":"traceutil/trace.go:171","msg":"trace[124514989] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1002; }","duration":"386.692199ms","start":"2026-02-24T13:04:32.322866Z","end":"2026-02-24T13:04:32.709559Z","steps":["trace[124514989] 'agreement among raft nodes before linearized reading' (duration: 384.667264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:32.709636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:32.322856Z","time spent":"386.767722ms","remote":"127.0.0.1:41634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-02-24T13:04:33.004106Z","caller":"traceutil/trace.go:171","msg":"trace[86984967] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1056; }","duration":"269.204649ms","start":"2026-02-24T13:04:32.734877Z","end":"2026-02-24T13:04:33.004081Z","steps":["trace[86984967] 'read index received' (duration: 259.26818ms)","trace[86984967] 'applied index is now lower than readState.Index' (duration: 9.935329ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:33.004244Z","caller":"traceutil/trace.go:171","msg":"trace[3203455] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"269.877707ms","start":"2026-02-24T13:04:32.734352Z","end":"2026-02-24T13:04:33.004229Z","steps":["trace[3203455] 'process raft request' (duration: 259.832545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:33.004796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.902628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-24T13:04:33.004992Z","caller":"traceutil/trace.go:171","msg":"trace[1355083560] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:1003; }","duration":"270.160385ms","start":"2026-02-24T13:04:32.734817Z","end":"2026-02-24T13:04:33.004978Z","steps":["trace[1355083560] 'agreement among raft nodes before linearized reading' (duration: 269.825056ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:34.163155Z","caller":"traceutil/trace.go:171","msg":"trace[2034369132] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"351.221146ms","start":"2026-02-24T13:04:33.811909Z","end":"2026-02-24T13:04:34.16313Z","steps":["trace[2034369132] 'read index received' (duration: 320.868632ms)","trace[2034369132] 'applied index is now lower than readState.Index' (duration: 30.351254ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:34.163295Z","caller":"traceutil/trace.go:171","msg":"trace[1562546519] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"398.479779ms","start":"2026-02-24T13:04:33.764802Z","end":"2026-02-24T13:04:34.163282Z","steps":["trace[1562546519] 'process raft request' (duration: 368.025222ms)","trace[1562546519] 'compare' (duration: 29.980064ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:34.163567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.547717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-24T13:04:34.163605Z","caller":"traceutil/trace.go:171","msg":"trace[628243309] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:1007; }","duration":"350.603799ms","start":"2026-02-24T13:04:33.812991Z","end":"2026-02-24T13:04:34.163595Z","steps":["trace[628243309] 'agreement among raft nodes before linearized reading' (duration: 350.443035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:34.163595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:33.764778Z","time spent":"398.552561ms","remote":"127.0.0.1:41290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T13:04:34.163707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.795351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T13:04:34.16375Z","caller":"traceutil/trace.go:171","msg":"trace[23639384] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1007; }","duration":"351.852554ms","start":"2026-02-24T13:04:33.811891Z","end":"2026-02-24T13:04:34.163744Z","steps":["trace[23639384] 'agreement among raft nodes before linearized reading' (duration: 351.771681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:34.163769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:33.811884Z","time spent":"351.879034ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T13:04:34.163627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:33.812985Z","time spent":"350.63757ms","remote":"127.0.0.1:41264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-24T13:04:34.164005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.673853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-02-24T13:04:34.164028Z","caller":"traceutil/trace.go:171","msg":"trace[1231457025] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:1007; }","duration":"149.699354ms","start":"2026-02-24T13:04:34.014323Z","end":"2026-02-24T13:04:34.164023Z","steps":["trace[1231457025] 'agreement among raft nodes before linearized reading' (duration: 149.578411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:34.164207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.616464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-02-24T13:04:34.164232Z","caller":"traceutil/trace.go:171","msg":"trace[376405935] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:1007; }","duration":"317.641975ms","start":"2026-02-24T13:04:33.846584Z","end":"2026-02-24T13:04:34.164226Z","steps":["trace[376405935] 'agreement among raft nodes before linearized reading' (duration: 317.554502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:34.164277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:33.84653Z","time spent":"317.740077ms","remote":"127.0.0.1:41264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188518,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-24T13:04:34.164405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.001221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-24T13:04:34.164427Z","caller":"traceutil/trace.go:171","msg":"trace[509821887] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:1007; }","duration":"340.036052ms","start":"2026-02-24T13:04:33.824386Z","end":"2026-02-24T13:04:34.164422Z","steps":["trace[509821887] 'agreement among raft nodes before linearized reading' (duration: 339.97557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:34.164469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:33.82438Z","time spent":"340.083244ms","remote":"127.0.0.1:41264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-24T13:04:34.164589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.959137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-24T13:04:34.164607Z","caller":"traceutil/trace.go:171","msg":"trace[1837093138] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:1007; }","duration":"340.992678ms","start":"2026-02-24T13:04:33.823609Z","end":"2026-02-24T13:04:34.164601Z","steps":["trace[1837093138] 'agreement among raft nodes before linearized reading' (duration: 340.939756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:34.164624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:33.823592Z","time spent":"341.028259ms","remote":"127.0.0.1:41264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-24T13:04:34.164696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.069884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-24T13:04:34.164711Z","caller":"traceutil/trace.go:171","msg":"trace[1962256484] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:1007; }","duration":"343.095565ms","start":"2026-02-24T13:04:33.821611Z","end":"2026-02-24T13:04:34.164706Z","steps":["trace[1962256484] 'agreement among raft nodes before linearized reading' (duration: 343.049273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:34.164726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:33.821605Z","time spent":"343.117466ms","remote":"127.0.0.1:41264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-24T13:04:34.164796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.597326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-24T13:04:34.164812Z","caller":"traceutil/trace.go:171","msg":"trace[2066354810] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:1007; }","duration":"344.624366ms","start":"2026-02-24T13:04:33.820183Z","end":"2026-02-24T13:04:34.164807Z","steps":["trace[2066354810] 'agreement among raft nodes before linearized reading' (duration: 344.577965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:34.16483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:33.820177Z","time spent":"344.649407ms","remote":"127.0.0.1:41264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13252,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-24T13:04:34.164894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.903542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-24T13:04:34.164908Z","caller":"traceutil/trace.go:171","msg":"trace[1221056574] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:1007; }","duration":"345.927052ms","start":"2026-02-24T13:04:33.818977Z","end":"2026-02-24T13:04:34.164904Z","steps":["trace[1221056574] 'agreement among raft nodes before linearized reading' (duration: 345.886301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:34.164923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:33.818974Z","time spent":"345.946122ms","remote":"127.0.0.1:41264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-24T13:04:34.164972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.839531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-24T13:04:34.165022Z","caller":"traceutil/trace.go:171","msg":"trace[851535706] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:1007; }","duration":"348.904303ms","start":"2026-02-24T13:04:33.816103Z","end":"2026-02-24T13:04:34.165007Z","steps":["trace[851535706] 'agreement among raft nodes before linearized reading' (duration: 348.764579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:34.165024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.971471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"warn","ts":"2026-02-24T13:04:34.165059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:33.816097Z","time spent":"348.951674ms","remote":"127.0.0.1:41264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-24T13:04:34.165082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.112407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-24T13:04:34.165105Z","caller":"traceutil/trace.go:171","msg":"trace[486608813] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:1007; }","duration":"346.147218ms","start":"2026-02-24T13:04:33.818952Z","end":"2026-02-24T13:04:34.165099Z","steps":["trace[486608813] 'agreement among raft nodes before linearized reading' (duration: 346.092007ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:34.165093Z","caller":"traceutil/trace.go:171","msg":"trace[1354151093] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:1007; }","duration":"350.062125ms","start":"2026-02-24T13:04:33.815012Z","end":"2026-02-24T13:04:34.165074Z","steps":["trace[1354151093] 'agreement among raft nodes before linearized reading' (duration: 349.859489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:34.165122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:33.818946Z","time spent":"346.171929ms","remote":"127.0.0.1:41264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-24T13:04:34.165141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:33.815006Z","time spent":"350.126736ms","remote":"127.0.0.1:41264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-02-24T13:04:34.915754Z","caller":"traceutil/trace.go:171","msg":"trace[892588538] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1064; }","duration":"121.510779ms","start":"2026-02-24T13:04:34.794222Z","end":"2026-02-24T13:04:34.915733Z","steps":["trace[892588538] 'read index received' (duration: 121.287463ms)","trace[892588538] 'applied index is now lower than readState.Index' (duration: 222.366µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:04:34.915902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.698055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:04:34.915944Z","caller":"traceutil/trace.go:171","msg":"trace[1676089057] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1010; }","duration":"121.757826ms","start":"2026-02-24T13:04:34.794175Z","end":"2026-02-24T13:04:34.915933Z","steps":["trace[1676089057] 'agreement among raft nodes before linearized reading' (duration: 121.658323ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:34.91627Z","caller":"traceutil/trace.go:171","msg":"trace[278426984] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"397.02987ms","start":"2026-02-24T13:04:34.519224Z","end":"2026-02-24T13:04:34.916254Z","steps":["trace[278426984] 'process raft request' (duration: 396.360272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:04:34.916358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:04:34.519209Z","time spent":"397.095281ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:04:34.916634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.098548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:04:34.916676Z","caller":"traceutil/trace.go:171","msg":"trace[1610000370] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1010; }","duration":"121.16295ms","start":"2026-02-24T13:04:34.795501Z","end":"2026-02-24T13:04:34.916664Z","steps":["trace[1610000370] 'agreement among raft nodes before linearized reading' (duration: 121.102778ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:04:35.873536Z","caller":"traceutil/trace.go:171","msg":"trace[1900479710] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"109.051771ms","start":"2026-02-24T13:04:35.764449Z","end":"2026-02-24T13:04:35.873501Z","steps":["trace[1900479710] 'process raft request' (duration: 61.053268ms)","trace[1900479710] 'compare' (duration: 47.828979ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:04:43.701608Z","caller":"traceutil/trace.go:171","msg":"trace[956098489] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"134.306126ms","start":"2026-02-24T13:04:43.567271Z","end":"2026-02-24T13:04:43.701577Z","steps":["trace[956098489] 'process raft request' (duration: 134.098141ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:05:01.291051Z","caller":"traceutil/trace.go:171","msg":"trace[1410728103] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"204.657357ms","start":"2026-02-24T13:05:01.086355Z","end":"2026-02-24T13:05:01.291012Z","steps":["trace[1410728103] 'process raft request' (duration: 162.05059ms)","trace[1410728103] 'compare' (duration: 42.221746ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:06:00.38226Z","caller":"traceutil/trace.go:171","msg":"trace[1787733761] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"188.203168ms","start":"2026-02-24T13:06:00.194031Z","end":"2026-02-24T13:06:00.382234Z","steps":["trace[1787733761] 'process raft request' (duration: 187.952581ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:07:20.323289Z","caller":"traceutil/trace.go:171","msg":"trace[1083542420] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"110.381214ms","start":"2026-02-24T13:07:20.212879Z","end":"2026-02-24T13:07:20.32326Z","steps":["trace[1083542420] 'process raft request' (duration: 110.255251ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:07:21.500669Z","caller":"traceutil/trace.go:171","msg":"trace[1683458118] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"198.726862ms","start":"2026-02-24T13:07:21.301922Z","end":"2026-02-24T13:07:21.500649Z","steps":["trace[1683458118] 'process raft request' (duration: 198.615369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:07:21.691731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.972534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:07:21.691845Z","caller":"traceutil/trace.go:171","msg":"trace[1643131278] linearizableReadLoop","detail":"{readStateIndex:1901; appliedIndex:1900; }","duration":"188.885576ms","start":"2026-02-24T13:07:21.502942Z","end":"2026-02-24T13:07:21.691828Z","steps":["trace[1643131278] 'read index received' (duration: 55.715666ms)","trace[1643131278] 'applied index is now lower than readState.Index' (duration: 133.16879ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:07:21.692169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.468435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T13:07:21.692175Z","caller":"traceutil/trace.go:171","msg":"trace[612391791] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"303.667168ms","start":"2026-02-24T13:07:21.388481Z","end":"2026-02-24T13:07:21.692148Z","steps":["trace[612391791] 'process raft request' (duration: 170.16315ms)","trace[612391791] 'compare' (duration: 132.84367ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:07:21.692236Z","caller":"traceutil/trace.go:171","msg":"trace[738114278] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1811; }","duration":"137.561827ms","start":"2026-02-24T13:07:21.554659Z","end":"2026-02-24T13:07:21.692221Z","steps":["trace[738114278] 'agreement among raft nodes before linearized reading' (duration: 137.400303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:07:21.692188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.236535ms","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:529"} {"level":"warn","ts":"2026-02-24T13:07:21.692394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:07:21.388452Z","time spent":"303.820232ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1768,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T13:07:21.692429Z","caller":"traceutil/trace.go:171","msg":"trace[649841886] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1811; }","duration":"189.493541ms","start":"2026-02-24T13:07:21.502918Z","end":"2026-02-24T13:07:21.692411Z","steps":["trace[649841886] 'agreement among raft nodes before linearized reading' (duration: 189.162583ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:07:21.889852Z","caller":"traceutil/trace.go:171","msg":"trace[1333648771] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"102.250544ms","start":"2026-02-24T13:07:21.78757Z","end":"2026-02-24T13:07:21.88982Z","steps":["trace[1333648771] 'process raft request' (duration: 101.792952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:07:22.201552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.244319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:07:22.201625Z","caller":"traceutil/trace.go:171","msg":"trace[156014742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1819; }","duration":"208.371553ms","start":"2026-02-24T13:07:21.993232Z","end":"2026-02-24T13:07:22.201604Z","steps":["trace[156014742] 'range keys from in-memory index tree' (duration: 208.065094ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:07:33.688904Z","caller":"traceutil/trace.go:171","msg":"trace[1928914782] linearizableReadLoop","detail":"{readStateIndex:1972; appliedIndex:1971; }","duration":"158.024372ms","start":"2026-02-24T13:07:33.530846Z","end":"2026-02-24T13:07:33.68887Z","steps":["trace[1928914782] 'read index received' (duration: 157.629301ms)","trace[1928914782] 'applied index is now lower than readState.Index' (duration: 393.881µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:07:33.689222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.34904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.79\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T13:07:33.689475Z","caller":"traceutil/trace.go:171","msg":"trace[2016542949] range","detail":"{range_begin:/registry/masterleases/199.19.213.79; range_end:; response_count:1; response_revision:1880; }","duration":"158.449573ms","start":"2026-02-24T13:07:33.530801Z","end":"2026-02-24T13:07:33.68925Z","steps":["trace[2016542949] 'agreement among raft nodes before linearized reading' (duration: 158.219097ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:07:33.69078Z","caller":"traceutil/trace.go:171","msg":"trace[620879823] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"438.570013ms","start":"2026-02-24T13:07:33.252142Z","end":"2026-02-24T13:07:33.690712Z","steps":["trace[620879823] 'process raft request' (duration: 436.493976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:07:33.692194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:07:33.252109Z","time spent":"439.863758ms","remote":"127.0.0.1:41460","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-02-24T13:07:33.924497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.357216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:07:33.924627Z","caller":"traceutil/trace.go:171","msg":"trace[638919339] linearizableReadLoop","detail":"{readStateIndex:1974; appliedIndex:1973; }","duration":"132.269744ms","start":"2026-02-24T13:07:33.79234Z","end":"2026-02-24T13:07:33.92461Z","steps":["trace[638919339] 'read index received' (duration: 31.491µs)","trace[638919339] 'applied index is now lower than readState.Index' (duration: 132.237033ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:07:33.924688Z","caller":"traceutil/trace.go:171","msg":"trace[1031722623] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"176.948695ms","start":"2026-02-24T13:07:33.747696Z","end":"2026-02-24T13:07:33.924645Z","steps":["trace[1031722623] 'process raft request' (duration: 44.322951ms)","trace[1031722623] 'compare' (duration: 132.203952ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:07:33.925167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.595652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-24T13:07:33.925382Z","caller":"traceutil/trace.go:171","msg":"trace[1458303743] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1881; }","duration":"133.062145ms","start":"2026-02-24T13:07:33.792294Z","end":"2026-02-24T13:07:33.925356Z","steps":["trace[1458303743] 'agreement among raft nodes before linearized reading' (duration: 132.411807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:07:34.203736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.585543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T13:07:34.204023Z","caller":"traceutil/trace.go:171","msg":"trace[1941700892] linearizableReadLoop","detail":"{readStateIndex:1975; appliedIndex:1974; }","duration":"273.356086ms","start":"2026-02-24T13:07:33.930629Z","end":"2026-02-24T13:07:34.203986Z","steps":["trace[1941700892] 'read index received' (duration: 123.482585ms)","trace[1941700892] 'applied index is now lower than readState.Index' (duration: 149.869751ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:07:34.204285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.616393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-24T13:07:34.204273Z","caller":"traceutil/trace.go:171","msg":"trace[1982377724] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"273.508161ms","start":"2026-02-24T13:07:33.930728Z","end":"2026-02-24T13:07:34.204237Z","steps":["trace[1982377724] 'process raft request' (duration: 273.161701ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:07:34.20434Z","caller":"traceutil/trace.go:171","msg":"trace[1952205008] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1884; }","duration":"273.738096ms","start":"2026-02-24T13:07:33.930587Z","end":"2026-02-24T13:07:34.204325Z","steps":["trace[1952205008] 'agreement among raft nodes before linearized reading' (duration: 273.562371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:07:34.204347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.448774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T13:07:34.20439Z","caller":"traceutil/trace.go:171","msg":"trace[1109566349] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1884; }","duration":"271.502335ms","start":"2026-02-24T13:07:33.932874Z","end":"2026-02-24T13:07:34.204377Z","steps":["trace[1109566349] 'agreement among raft nodes before linearized reading' (duration: 271.440934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:07:34.204452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.660844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T13:07:34.204495Z","caller":"traceutil/trace.go:171","msg":"trace[1250954310] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1884; }","duration":"146.759106ms","start":"2026-02-24T13:07:34.057725Z","end":"2026-02-24T13:07:34.204484Z","steps":["trace[1250954310] 'agreement among raft nodes before linearized reading' (duration: 146.665774ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:07:34.204057Z","caller":"traceutil/trace.go:171","msg":"trace[1105877249] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"271.821785ms","start":"2026-02-24T13:07:33.932199Z","end":"2026-02-24T13:07:34.204021Z","steps":["trace[1105877249] 'process raft request' (duration: 271.764963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:07:34.204686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.976277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T13:07:34.204719Z","caller":"traceutil/trace.go:171","msg":"trace[572599960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1884; }","duration":"252.058268ms","start":"2026-02-24T13:07:33.952651Z","end":"2026-02-24T13:07:34.204709Z","steps":["trace[572599960] 'agreement among raft nodes before linearized reading' (duration: 251.925485ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:07:34.204076Z","caller":"traceutil/trace.go:171","msg":"trace[281048879] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"274.073286ms","start":"2026-02-24T13:07:33.929976Z","end":"2026-02-24T13:07:34.204049Z","steps":["trace[281048879] 'process raft request' (duration: 124.088502ms)","trace[281048879] 'compare' (duration: 149.459049ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:07:34.852121Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":662764236348424007,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T13:07:34.901321Z","caller":"traceutil/trace.go:171","msg":"trace[2116584599] linearizableReadLoop","detail":"{readStateIndex:1979; appliedIndex:1978; }","duration":"549.913599ms","start":"2026-02-24T13:07:34.351354Z","end":"2026-02-24T13:07:34.901268Z","steps":["trace[2116584599] 'read index received' (duration: 549.688963ms)","trace[2116584599] 'applied index is now lower than readState.Index' (duration: 223.426µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T13:07:34.901365Z","caller":"traceutil/trace.go:171","msg":"trace[333561352] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"690.55829ms","start":"2026-02-24T13:07:34.210777Z","end":"2026-02-24T13:07:34.901335Z","steps":["trace[333561352] 'process raft request' (duration: 690.282182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:07:34.901525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.152226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T13:07:34.901534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:07:34.210757Z","time spent":"690.692313ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T13:07:34.901576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.180844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-24T13:07:34.901592Z","caller":"traceutil/trace.go:171","msg":"trace[330022014] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1886; }","duration":"550.273069ms","start":"2026-02-24T13:07:34.351301Z","end":"2026-02-24T13:07:34.901574Z","steps":["trace[330022014] 'agreement among raft nodes before linearized reading' (duration: 550.118185ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:07:34.901616Z","caller":"traceutil/trace.go:171","msg":"trace[1291479766] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1886; }","duration":"506.265887ms","start":"2026-02-24T13:07:34.395338Z","end":"2026-02-24T13:07:34.901604Z","steps":["trace[1291479766] 'agreement among raft nodes before linearized reading' (duration: 506.115283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:07:34.901633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:07:34.351279Z","time spent":"550.344441ms","remote":"127.0.0.1:41632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-24T13:07:34.901658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:07:34.395313Z","time spent":"506.336958ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-24T13:07:34.901784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.273479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T13:07:34.901888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.830373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T13:07:34.901872Z","caller":"traceutil/trace.go:171","msg":"trace[432096873] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1886; }","duration":"130.374102ms","start":"2026-02-24T13:07:34.771464Z","end":"2026-02-24T13:07:34.901839Z","steps":["trace[432096873] 'agreement among raft nodes before linearized reading' (duration: 130.186217ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:07:34.901933Z","caller":"traceutil/trace.go:171","msg":"trace[154609447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1886; }","duration":"172.911105ms","start":"2026-02-24T13:07:34.729011Z","end":"2026-02-24T13:07:34.901922Z","steps":["trace[154609447] 'agreement among raft nodes before linearized reading' (duration: 172.822502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:07:34.901809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.526308ms","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-02-24T13:07:34.902077Z","caller":"traceutil/trace.go:171","msg":"trace[1963179091] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1886; }","duration":"298.828536ms","start":"2026-02-24T13:07:34.603231Z","end":"2026-02-24T13:07:34.902059Z","steps":["trace[1963179091] 'agreement among raft nodes before linearized reading' (duration: 298.446146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:07:34.9034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.858631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-24T13:07:34.90355Z","caller":"traceutil/trace.go:171","msg":"trace[552340751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1886; }","duration":"457.383092ms","start":"2026-02-24T13:07:34.446142Z","end":"2026-02-24T13:07:34.903525Z","steps":["trace[552340751] 'agreement among raft nodes before linearized reading' (duration: 455.587623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:07:34.903652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T13:07:34.44612Z","time spent":"457.518626ms","remote":"127.0.0.1:41460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T13:08:03.799261Z","caller":"traceutil/trace.go:171","msg":"trace[1360272720] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"249.87162ms","start":"2026-02-24T13:08:03.549376Z","end":"2026-02-24T13:08:03.799248Z","steps":["trace[1360272720] 'process raft request' (duration: 248.555104ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:08:03.799254Z","caller":"traceutil/trace.go:171","msg":"trace[544385010] linearizableReadLoop","detail":"{readStateIndex:2125; appliedIndex:2124; }","duration":"239.078398ms","start":"2026-02-24T13:08:03.560158Z","end":"2026-02-24T13:08:03.799237Z","steps":["trace[544385010] 'read index received' (duration: 237.743812ms)","trace[544385010] 'applied index is now lower than readState.Index' (duration: 1.333956ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T13:08:03.799469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.245512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-24T13:08:03.799499Z","caller":"traceutil/trace.go:171","msg":"trace[973094795] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2026; }","duration":"239.351685ms","start":"2026-02-24T13:08:03.56014Z","end":"2026-02-24T13:08:03.799491Z","steps":["trace[973094795] 'agreement among raft nodes before linearized reading' (duration: 239.130139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T13:08:06.748549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.871738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-5d8ff44fc9-86rhd\" ","response":"range_response_count:1 size:12788"} {"level":"info","ts":"2026-02-24T13:08:06.748609Z","caller":"traceutil/trace.go:171","msg":"trace[751328193] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-5d8ff44fc9-86rhd; range_end:; response_count:1; response_revision:2041; }","duration":"143.972141ms","start":"2026-02-24T13:08:06.604622Z","end":"2026-02-24T13:08:06.748595Z","steps":["trace[751328193] 'range keys from in-memory index tree' (duration: 143.60375ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T13:08:09.306996Z","caller":"traceutil/trace.go:171","msg":"trace[913352405] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"141.632077ms","start":"2026-02-24T13:08:09.165339Z","end":"2026-02-24T13:08:09.306971Z","steps":["trace[913352405] 'process raft request' (duration: 141.326339ms)"],"step_count":1}