{"level":"warn","ts":"2026-05-06T01:17:56.197434Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T01:17:56.197512Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.61: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.61:2380","--initial-cluster=instance=https://199.19.213.61:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.61:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.61: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-05-06T01:17:56.197574Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T01:17:56.197586Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.61:2380"]} {"level":"info","ts":"2026-05-06T01:17:56.197603Z","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-05-06T01:17:56.198306Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.61:2379"]} {"level":"info","ts":"2026-05-06T01:17:56.198374Z","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.61:2380"],"listen-peer-urls":["https://199.19.213.61:2380"],"advertise-client-urls":["https://199.19.213.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.61: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-05-06T01:17:56.202179Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.575211ms"} {"level":"info","ts":"2026-05-06T01:17:56.212968Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d1484a2210d3c2b8","cluster-id":"27fc2dec3fac595c"} {"level":"info","ts":"2026-05-06T01:17:56.213042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T01:17:56.213062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became follower at term 0"} {"level":"info","ts":"2026-05-06T01:17:56.213069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d1484a2210d3c2b8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T01:17:56.213075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became follower at term 1"} {"level":"info","ts":"2026-05-06T01:17:56.213104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 switched to configuration voters=(15080384862421762744)"} {"level":"warn","ts":"2026-05-06T01:17:56.222519Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T01:17:56.223659Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T01:17:56.22575Z","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-05-06T01:17:56.228252Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d1484a2210d3c2b8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T01:17:56.228374Z","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-05-06T01:17:56.228456Z","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-05-06T01:17:56.228469Z","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-05-06T01:17:56.230295Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d1484a2210d3c2b8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T01:17:56.230869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 switched to configuration voters=(15080384862421762744)"} {"level":"info","ts":"2026-05-06T01:17:56.231001Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"27fc2dec3fac595c","local-member-id":"d1484a2210d3c2b8","added-peer-id":"d1484a2210d3c2b8","added-peer-peer-urls":["https://199.19.213.61:2380"]} {"level":"info","ts":"2026-05-06T01:17:56.23267Z","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-05-06T01:17:56.232861Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d1484a2210d3c2b8","initial-advertise-peer-urls":["https://199.19.213.61:2380"],"listen-peer-urls":["https://199.19.213.61:2380"],"advertise-client-urls":["https://199.19.213.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T01:17:56.232888Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T01:17:56.232972Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.61:2380"} {"level":"info","ts":"2026-05-06T01:17:56.232982Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.61:2380"} {"level":"info","ts":"2026-05-06T01:17:56.313856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T01:17:56.313921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T01:17:56.31395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 received MsgPreVoteResp from d1484a2210d3c2b8 at term 1"} {"level":"info","ts":"2026-05-06T01:17:56.313971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became candidate at term 2"} {"level":"info","ts":"2026-05-06T01:17:56.313982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 received MsgVoteResp from d1484a2210d3c2b8 at term 2"} {"level":"info","ts":"2026-05-06T01:17:56.314149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became leader at term 2"} {"level":"info","ts":"2026-05-06T01:17:56.314279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d1484a2210d3c2b8 elected leader d1484a2210d3c2b8 at term 2"} {"level":"info","ts":"2026-05-06T01:17:56.323972Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:17:56.325309Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T01:17:56.325308Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d1484a2210d3c2b8","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.61:2379]}","request-path":"/0/members/d1484a2210d3c2b8/attributes","cluster-id":"27fc2dec3fac595c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T01:17:56.32533Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T01:17:56.325528Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"27fc2dec3fac595c","local-member-id":"d1484a2210d3c2b8","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:17:56.325644Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T01:17:56.32572Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T01:17:56.325739Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:17:56.325808Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:17:56.328728Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T01:17:56.330348Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.61:2379"} {"level":"warn","ts":"2026-05-06T01:18:24.664948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.259106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:18:24.66508Z","caller":"traceutil/trace.go:171","msg":"trace[268381392] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"265.403967ms","start":"2026-05-06T01:18:24.399656Z","end":"2026-05-06T01:18:24.66506Z","steps":["trace[268381392] 'process raft request' (duration: 122.470276ms)","trace[268381392] 'compare' (duration: 142.084682ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:25.094485Z","caller":"traceutil/trace.go:171","msg":"trace[194350666] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"149.554173ms","start":"2026-05-06T01:18:24.944901Z","end":"2026-05-06T01:18:25.094456Z","steps":["trace[194350666] 'process raft request' (duration: 149.436201ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:25.29769Z","caller":"traceutil/trace.go:171","msg":"trace[1426565271] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"278.301151ms","start":"2026-05-06T01:18:25.019338Z","end":"2026-05-06T01:18:25.297639Z","steps":["trace[1426565271] 'process raft request' (duration: 261.740384ms)","trace[1426565271] 'compare' (duration: 16.272441ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:25.298141Z","caller":"traceutil/trace.go:171","msg":"trace[2038255287] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"217.035027ms","start":"2026-05-06T01:18:25.081077Z","end":"2026-05-06T01:18:25.298112Z","steps":["trace[2038255287] 'process raft request' (duration: 216.507535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:25.515605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.834191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:18:25.515838Z","caller":"traceutil/trace.go:171","msg":"trace[606275954] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"413.59151ms","start":"2026-05-06T01:18:25.102215Z","end":"2026-05-06T01:18:25.515806Z","steps":["trace[606275954] 'process raft request' (duration: 295.468503ms)","trace[606275954] 'compare' (duration: 117.656666ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:25.516038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:25.102186Z","time spent":"413.803165ms","remote":"127.0.0.1:55724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15015,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:18:25.724363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.639046ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-06T01:18:25.724642Z","caller":"traceutil/trace.go:171","msg":"trace[230578774] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:499; }","duration":"539.558305ms","start":"2026-05-06T01:18:25.185038Z","end":"2026-05-06T01:18:25.724596Z","steps":["trace[230578774] 'read index received' (duration: 96.131377ms)","trace[230578774] 'applied index is now lower than readState.Index' (duration: 443.421418ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:25.724733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.685829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:25.724799Z","caller":"traceutil/trace.go:171","msg":"trace[204116248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"539.781641ms","start":"2026-05-06T01:18:25.184987Z","end":"2026-05-06T01:18:25.724768Z","steps":["trace[204116248] 'agreement among raft nodes before linearized reading' (duration: 539.694739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:25.724852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:25.184973Z","time spent":"539.870703ms","remote":"127.0.0.1:55544","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-05-06T01:18:25.72548Z","caller":"traceutil/trace.go:171","msg":"trace[1004001236] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"422.929482ms","start":"2026-05-06T01:18:25.302539Z","end":"2026-05-06T01:18:25.725468Z","steps":["trace[1004001236] 'process raft request' (duration: 421.944089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:25.725572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:25.302514Z","time spent":"423.002814ms","remote":"127.0.0.1:55612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:18:25.725629Z","caller":"traceutil/trace.go:171","msg":"trace[478783029] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"420.083187ms","start":"2026-05-06T01:18:25.305528Z","end":"2026-05-06T01:18:25.725611Z","steps":["trace[478783029] 'process raft request' (duration: 419.837101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:25.725714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:25.305514Z","time spent":"420.155058ms","remote":"127.0.0.1:55724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3576,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:18:25.72608Z","caller":"traceutil/trace.go:171","msg":"trace[1566288091] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"420.414454ms","start":"2026-05-06T01:18:25.30565Z","end":"2026-05-06T01:18:25.726064Z","steps":["trace[1566288091] 'process raft request' (duration: 420.079136ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:25.726082Z","caller":"traceutil/trace.go:171","msg":"trace[91661093] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"204.693477ms","start":"2026-05-06T01:18:25.521366Z","end":"2026-05-06T01:18:25.72606Z","steps":["trace[91661093] 'process raft request' (duration: 204.442271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:25.72636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:25.305642Z","time spent":"420.479206ms","remote":"127.0.0.1:55998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4320,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:25.726608Z","caller":"traceutil/trace.go:171","msg":"trace[34193893] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"204.647476ms","start":"2026-05-06T01:18:25.521368Z","end":"2026-05-06T01:18:25.726015Z","steps":["trace[34193893] 'process raft request' (duration: 204.568274ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:25.752555Z","caller":"traceutil/trace.go:171","msg":"trace[821274999] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"224.195851ms","start":"2026-05-06T01:18:25.528334Z","end":"2026-05-06T01:18:25.75253Z","steps":["trace[821274999] 'process raft request' (duration: 223.994265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:25.753514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.710722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-05-06T01:18:25.753702Z","caller":"traceutil/trace.go:171","msg":"trace[880099416] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:493; }","duration":"384.890925ms","start":"2026-05-06T01:18:25.368699Z","end":"2026-05-06T01:18:25.75359Z","steps":["trace[880099416] 'agreement among raft nodes before linearized reading' (duration: 384.132868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:25.753823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:25.368671Z","time spent":"385.08252ms","remote":"127.0.0.1:55638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329979,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-05-06T01:18:25.956432Z","caller":"traceutil/trace.go:171","msg":"trace[738142405] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:509; }","duration":"203.903718ms","start":"2026-05-06T01:18:25.752503Z","end":"2026-05-06T01:18:25.956407Z","steps":["trace[738142405] 'read index received' (duration: 161.946974ms)","trace[738142405] 'applied index is now lower than readState.Index' (duration: 41.955884ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:25.956767Z","caller":"traceutil/trace.go:171","msg":"trace[1654904803] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"222.142404ms","start":"2026-05-06T01:18:25.73461Z","end":"2026-05-06T01:18:25.956752Z","steps":["trace[1654904803] 'process raft request' (duration: 179.863722ms)","trace[1654904803] 'compare' (duration: 41.677769ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:25.956958Z","caller":"traceutil/trace.go:171","msg":"trace[295976711] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"222.168965ms","start":"2026-05-06T01:18:25.734781Z","end":"2026-05-06T01:18:25.956949Z","steps":["trace[295976711] 'process raft request' (duration: 221.479059ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:25.957099Z","caller":"traceutil/trace.go:171","msg":"trace[8367874] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"221.487499ms","start":"2026-05-06T01:18:25.735602Z","end":"2026-05-06T01:18:25.95709Z","steps":["trace[8367874] 'process raft request' (duration: 220.71385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:25.95735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.259177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-stqxz\" ","response":"range_response_count:1 size:15076"} {"level":"warn","ts":"2026-05-06T01:18:25.95735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.71946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:25.957412Z","caller":"traceutil/trace.go:171","msg":"trace[1290137245] range","detail":"{range_begin:/registry/pods/kube-system/cilium-stqxz; range_end:; response_count:1; response_revision:497; }","duration":"222.431101ms","start":"2026-05-06T01:18:25.734957Z","end":"2026-05-06T01:18:25.957388Z","steps":["trace[1290137245] 'agreement among raft nodes before linearized reading' (duration: 222.284698ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:25.957416Z","caller":"traceutil/trace.go:171","msg":"trace[1949315677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:497; }","duration":"230.832962ms","start":"2026-05-06T01:18:25.726563Z","end":"2026-05-06T01:18:25.957396Z","steps":["trace[1949315677] 'agreement among raft nodes before linearized reading' (duration: 230.71064ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:25.957538Z","caller":"traceutil/trace.go:171","msg":"trace[124660240] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"221.586451ms","start":"2026-05-06T01:18:25.73594Z","end":"2026-05-06T01:18:25.957526Z","steps":["trace[124660240] 'process raft request' (duration: 220.415194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:25.957725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.271408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-05-06T01:18:25.957753Z","caller":"traceutil/trace.go:171","msg":"trace[947822798] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:497; }","duration":"222.326678ms","start":"2026-05-06T01:18:25.735418Z","end":"2026-05-06T01:18:25.957745Z","steps":["trace[947822798] 'agreement among raft nodes before linearized reading' (duration: 222.273777ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:26.188022Z","caller":"traceutil/trace.go:171","msg":"trace[2084029950] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"157.270338ms","start":"2026-05-06T01:18:26.030725Z","end":"2026-05-06T01:18:26.187995Z","steps":["trace[2084029950] 'process raft request' (duration: 157.074124ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:26.196063Z","caller":"traceutil/trace.go:171","msg":"trace[497792249] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"163.134481ms","start":"2026-05-06T01:18:26.032897Z","end":"2026-05-06T01:18:26.196031Z","steps":["trace[497792249] 'process raft request' (duration: 162.823574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:28.091552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.145724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:18:28.091703Z","caller":"traceutil/trace.go:171","msg":"trace[1778975877] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"623.327321ms","start":"2026-05-06T01:18:27.468349Z","end":"2026-05-06T01:18:28.091677Z","steps":["trace[1778975877] 'process raft request' (duration: 374.94077ms)","trace[1778975877] 'compare' (duration: 247.840268ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:28.091785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:27.468325Z","time spent":"623.416003ms","remote":"127.0.0.1:55590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:28.339963Z","caller":"traceutil/trace.go:171","msg":"trace[1568345468] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"870.77589ms","start":"2026-05-06T01:18:27.469153Z","end":"2026-05-06T01:18:28.339929Z","steps":["trace[1568345468] 'process raft request' (duration: 870.424652ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:28.340003Z","caller":"traceutil/trace.go:171","msg":"trace[498643508] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"866.282958ms","start":"2026-05-06T01:18:27.473672Z","end":"2026-05-06T01:18:28.339955Z","steps":["trace[498643508] 'process raft request' (duration: 866.053572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:28.341244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:27.469125Z","time spent":"870.938454ms","remote":"127.0.0.1:56070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:18:28.341504Z","caller":"traceutil/trace.go:171","msg":"trace[344091244] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"867.65208ms","start":"2026-05-06T01:18:27.473744Z","end":"2026-05-06T01:18:28.341396Z","steps":["trace[344091244] 'process raft request' (duration: 866.063163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:28.341655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:27.473667Z","time spent":"867.883014ms","remote":"127.0.0.1:55590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:28.34186Z","caller":"traceutil/trace.go:171","msg":"trace[1295680537] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"863.548146ms","start":"2026-05-06T01:18:27.478106Z","end":"2026-05-06T01:18:28.341654Z","steps":["trace[1295680537] 'process raft request' (duration: 861.891217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:28.341956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:27.478084Z","time spent":"863.837582ms","remote":"127.0.0.1:56070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:18:28.343236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:27.473636Z","time spent":"867.709351ms","remote":"127.0.0.1:55590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:28.343696Z","caller":"traceutil/trace.go:171","msg":"trace[1725952651] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"866.234467ms","start":"2026-05-06T01:18:27.473856Z","end":"2026-05-06T01:18:28.34009Z","steps":["trace[1725952651] 'process raft request' (duration: 866.069763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:28.343744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:27.473808Z","time spent":"869.910331ms","remote":"127.0.0.1:55590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:18:28.344662Z","caller":"traceutil/trace.go:171","msg":"trace[1034911254] transaction","detail":"{read_only:false; number_of_response:1; response_revision:528; }","duration":"865.574972ms","start":"2026-05-06T01:18:27.479068Z","end":"2026-05-06T01:18:28.344643Z","steps":["trace[1034911254] 'process raft request' (duration: 860.988717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:28.344741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:27.479059Z","time spent":"865.635073ms","remote":"127.0.0.1:55590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7733,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:28.345079Z","caller":"traceutil/trace.go:171","msg":"trace[845071179] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"860.67991ms","start":"2026-05-06T01:18:27.484377Z","end":"2026-05-06T01:18:28.345057Z","steps":["trace[845071179] 'process raft request' (duration: 858.9584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:28.345234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:27.484367Z","time spent":"860.759122ms","remote":"127.0.0.1:55590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:28.351146Z","caller":"traceutil/trace.go:171","msg":"trace[1290961283] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"866.675637ms","start":"2026-05-06T01:18:27.484457Z","end":"2026-05-06T01:18:28.351133Z","steps":["trace[1290961283] 'process raft request' (duration: 860.64776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:28.351212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:27.484425Z","time spent":"866.750758ms","remote":"127.0.0.1:55590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:28.352732Z","caller":"traceutil/trace.go:171","msg":"trace[1810433237] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"864.864316ms","start":"2026-05-06T01:18:27.487845Z","end":"2026-05-06T01:18:28.352709Z","steps":["trace[1810433237] 'process raft request' (duration: 863.127856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:28.352844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:27.487838Z","time spent":"864.951358ms","remote":"127.0.0.1:55590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:18:28.353547Z","caller":"traceutil/trace.go:171","msg":"trace[1930237047] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"854.641684ms","start":"2026-05-06T01:18:27.498891Z","end":"2026-05-06T01:18:28.353533Z","steps":["trace[1930237047] 'process raft request' (duration: 852.320591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:28.353607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:27.498875Z","time spent":"854.704925ms","remote":"127.0.0.1:55590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:18:28.358905Z","caller":"traceutil/trace.go:171","msg":"trace[1324944816] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:541; }","duration":"175.35838ms","start":"2026-05-06T01:18:28.1835Z","end":"2026-05-06T01:18:28.358858Z","steps":["trace[1324944816] 'read index received' (duration: 155.983039ms)","trace[1324944816] 'applied index is now lower than readState.Index' (duration: 19.374701ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:28.359059Z","caller":"traceutil/trace.go:171","msg":"trace[794492650] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"769.848135ms","start":"2026-05-06T01:18:27.589205Z","end":"2026-05-06T01:18:28.359053Z","steps":["trace[794492650] 'process raft request' (duration: 762.125178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:28.35913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:27.589169Z","time spent":"769.910086ms","remote":"127.0.0.1:55590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:18:28.359252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.753539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:28.359279Z","caller":"traceutil/trace.go:171","msg":"trace[1529024000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"175.779319ms","start":"2026-05-06T01:18:28.183488Z","end":"2026-05-06T01:18:28.359268Z","steps":["trace[1529024000] 'agreement among raft nodes before linearized reading' (duration: 175.720978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:28.35946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.170982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-06T01:18:28.359478Z","caller":"traceutil/trace.go:171","msg":"trace[1008218346] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:533; }","duration":"152.211453ms","start":"2026-05-06T01:18:28.207262Z","end":"2026-05-06T01:18:28.359474Z","steps":["trace[1008218346] 'agreement among raft nodes before linearized reading' (duration: 152.127701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:28.359667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.534892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-05-06T01:18:28.359762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.347566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-06T01:18:28.359783Z","caller":"traceutil/trace.go:171","msg":"trace[628109411] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"116.388948ms","start":"2026-05-06T01:18:28.243389Z","end":"2026-05-06T01:18:28.359778Z","steps":["trace[628109411] 'agreement among raft nodes before linearized reading' (duration: 116.354477ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:28.359775Z","caller":"traceutil/trace.go:171","msg":"trace[1937153902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:533; }","duration":"149.738496ms","start":"2026-05-06T01:18:28.210021Z","end":"2026-05-06T01:18:28.35976Z","steps":["trace[1937153902] 'agreement among raft nodes before linearized reading' (duration: 149.561082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:28.861987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138339846781310,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T01:18:29.250099Z","caller":"traceutil/trace.go:171","msg":"trace[1833006947] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"890.904418ms","start":"2026-05-06T01:18:28.359157Z","end":"2026-05-06T01:18:29.250061Z","steps":["trace[1833006947] 'process raft request' (duration: 880.196124ms)","trace[1833006947] 'compare' (duration: 10.417537ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:29.250315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:28.359134Z","time spent":"891.062652ms","remote":"127.0.0.1:55590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:29.253141Z","caller":"traceutil/trace.go:171","msg":"trace[1125643514] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"890.53996ms","start":"2026-05-06T01:18:28.362576Z","end":"2026-05-06T01:18:29.253115Z","steps":["trace[1125643514] 'process raft request' (duration: 889.734182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:29.253262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:28.362565Z","time spent":"890.637442ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:29.253576Z","caller":"traceutil/trace.go:171","msg":"trace[302450468] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"885.883684ms","start":"2026-05-06T01:18:28.367666Z","end":"2026-05-06T01:18:29.253549Z","steps":["trace[302450468] 'process raft request' (duration: 884.752119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:29.253793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:28.367657Z","time spent":"885.977456ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:29.253937Z","caller":"traceutil/trace.go:171","msg":"trace[62190463] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"890.481277ms","start":"2026-05-06T01:18:28.3634Z","end":"2026-05-06T01:18:29.253881Z","steps":["trace[62190463] 'process raft request' (duration: 888.952753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:29.254124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:28.363393Z","time spent":"890.632842ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:29.254519Z","caller":"traceutil/trace.go:171","msg":"trace[2041520779] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"869.526772ms","start":"2026-05-06T01:18:28.384974Z","end":"2026-05-06T01:18:29.254501Z","steps":["trace[2041520779] 'process raft request' (duration: 867.480005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:29.2546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:28.384949Z","time spent":"869.610155ms","remote":"127.0.0.1:55590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:29.254677Z","caller":"traceutil/trace.go:171","msg":"trace[288507278] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"862.730468ms","start":"2026-05-06T01:18:28.391931Z","end":"2026-05-06T01:18:29.254661Z","steps":["trace[288507278] 'process raft request' (duration: 860.715722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:29.254756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:28.39191Z","time spent":"862.811329ms","remote":"127.0.0.1:55590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:29.254726Z","caller":"traceutil/trace.go:171","msg":"trace[1964234610] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"891.215216ms","start":"2026-05-06T01:18:28.363435Z","end":"2026-05-06T01:18:29.25465Z","steps":["trace[1964234610] 'process raft request' (duration: 888.952124ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:29.254863Z","caller":"traceutil/trace.go:171","msg":"trace[240259921] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"895.401161ms","start":"2026-05-06T01:18:28.35945Z","end":"2026-05-06T01:18:29.254851Z","steps":["trace[240259921] 'process raft request' (duration: 892.599277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:29.25489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:28.363416Z","time spent":"891.386959ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:18:29.254937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:28.359425Z","time spent":"895.475192ms","remote":"127.0.0.1:55590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:29.255036Z","caller":"traceutil/trace.go:171","msg":"trace[75571373] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"895.200696ms","start":"2026-05-06T01:18:28.359748Z","end":"2026-05-06T01:18:29.254949Z","steps":["trace[75571373] 'process raft request' (duration: 892.490785ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:29.255172Z","caller":"traceutil/trace.go:171","msg":"trace[1056956766] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"841.057834ms","start":"2026-05-06T01:18:28.4141Z","end":"2026-05-06T01:18:29.255158Z","steps":["trace[1056956766] 'process raft request' (duration: 838.761202ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:29.255251Z","caller":"traceutil/trace.go:171","msg":"trace[550731572] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"810.004928ms","start":"2026-05-06T01:18:28.445231Z","end":"2026-05-06T01:18:29.255236Z","steps":["trace[550731572] 'process raft request' (duration: 809.270211ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:29.255303Z","caller":"traceutil/trace.go:171","msg":"trace[1404683260] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"713.741968ms","start":"2026-05-06T01:18:28.541548Z","end":"2026-05-06T01:18:29.25529Z","steps":["trace[1404683260] 'process raft request' (duration: 713.578794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:29.255278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:28.414053Z","time spent":"841.154106ms","remote":"127.0.0.1:55590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:18:29.255323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:28.445204Z","time spent":"810.082309ms","remote":"127.0.0.1:55590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:18:29.255387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:28.541519Z","time spent":"713.82835ms","remote":"127.0.0.1:55804","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-05-06T01:18:29.255247Z","caller":"traceutil/trace.go:171","msg":"trace[1911919212] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:552; }","duration":"894.335306ms","start":"2026-05-06T01:18:28.360877Z","end":"2026-05-06T01:18:29.255212Z","steps":["trace[1911919212] 'read index received' (duration: 878.493515ms)","trace[1911919212] 'applied index is now lower than readState.Index' (duration: 15.840131ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:29.255479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.52716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:29.255759Z","caller":"traceutil/trace.go:171","msg":"trace[3297575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"894.908699ms","start":"2026-05-06T01:18:28.360838Z","end":"2026-05-06T01:18:29.255746Z","steps":["trace[3297575] 'agreement among raft nodes before linearized reading' (duration: 894.49764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:29.255809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:28.359735Z","time spent":"895.40041ms","remote":"127.0.0.1:55590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:18:29.255864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:28.360823Z","time spent":"895.030212ms","remote":"127.0.0.1:55556","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-05-06T01:18:29.994958Z","caller":"traceutil/trace.go:171","msg":"trace[845595428] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"139.621506ms","start":"2026-05-06T01:18:29.855319Z","end":"2026-05-06T01:18:29.99494Z","steps":["trace[845595428] 'process raft request' (duration: 139.458983ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:30.011835Z","caller":"traceutil/trace.go:171","msg":"trace[1075359924] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"134.338136ms","start":"2026-05-06T01:18:29.877445Z","end":"2026-05-06T01:18:30.011783Z","steps":["trace[1075359924] 'process raft request' (duration: 130.588231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:35.022414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.052858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:35.022531Z","caller":"traceutil/trace.go:171","msg":"trace[1916306838] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:613; }","duration":"115.232261ms","start":"2026-05-06T01:18:34.907279Z","end":"2026-05-06T01:18:35.022511Z","steps":["trace[1916306838] 'range keys from in-memory index tree' (duration: 114.933204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:35.02257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.309901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:35.022665Z","caller":"traceutil/trace.go:171","msg":"trace[617497196] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:613; }","duration":"165.437824ms","start":"2026-05-06T01:18:34.857211Z","end":"2026-05-06T01:18:35.022649Z","steps":["trace[617497196] 'count revisions from in-memory index tree' (duration: 165.25535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:35.022793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.126629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:35.022874Z","caller":"traceutil/trace.go:171","msg":"trace[2069669579] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:613; }","duration":"115.230332ms","start":"2026-05-06T01:18:34.907628Z","end":"2026-05-06T01:18:35.022859Z","steps":["trace[2069669579] 'range keys from in-memory index tree' (duration: 115.092059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:35.022981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.849853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:35.023009Z","caller":"traceutil/trace.go:171","msg":"trace[1927287591] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:613; }","duration":"165.931795ms","start":"2026-05-06T01:18:34.857071Z","end":"2026-05-06T01:18:35.023002Z","steps":["trace[1927287591] 'range keys from in-memory index tree' (duration: 165.810092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:35.023068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.678981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:35.023121Z","caller":"traceutil/trace.go:171","msg":"trace[712075473] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:613; }","duration":"115.753634ms","start":"2026-05-06T01:18:34.907355Z","end":"2026-05-06T01:18:35.023108Z","steps":["trace[712075473] 'count revisions from in-memory index tree' (duration: 115.645381ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:36.145825Z","caller":"traceutil/trace.go:171","msg":"trace[798286078] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"383.028924ms","start":"2026-05-06T01:18:35.762767Z","end":"2026-05-06T01:18:36.145796Z","steps":["trace[798286078] 'read index received' (duration: 381.908328ms)","trace[798286078] 'applied index is now lower than readState.Index' (duration: 1.119186ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:36.146564Z","caller":"traceutil/trace.go:171","msg":"trace[756532085] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"396.645024ms","start":"2026-05-06T01:18:35.749323Z","end":"2026-05-06T01:18:36.145968Z","steps":["trace[756532085] 'process raft request' (duration: 395.62685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:36.146734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.677329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:36.146834Z","caller":"traceutil/trace.go:171","msg":"trace[1926237472] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:624; }","duration":"383.855193ms","start":"2026-05-06T01:18:35.762956Z","end":"2026-05-06T01:18:36.146811Z","steps":["trace[1926237472] 'agreement among raft nodes before linearized reading' (duration: 383.492125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:36.146864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:35.749292Z","time spent":"397.411212ms","remote":"127.0.0.1:55638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:18:36.146908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:35.762941Z","time spent":"383.952155ms","remote":"127.0.0.1:48294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:18:36.147255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.973615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:36.147511Z","caller":"traceutil/trace.go:171","msg":"trace[1969121309] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:624; }","duration":"384.219391ms","start":"2026-05-06T01:18:35.763189Z","end":"2026-05-06T01:18:36.147408Z","steps":["trace[1969121309] 'agreement among raft nodes before linearized reading' (duration: 383.26747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:36.147644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:35.763181Z","time spent":"384.434676ms","remote":"127.0.0.1:48294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-05-06T01:18:36.148316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.488389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:36.148503Z","caller":"traceutil/trace.go:171","msg":"trace[220456139] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:624; }","duration":"385.675814ms","start":"2026-05-06T01:18:35.762765Z","end":"2026-05-06T01:18:36.14844Z","steps":["trace[220456139] 'agreement among raft nodes before linearized reading' (duration: 383.27032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:36.148575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:35.762742Z","time spent":"385.815698ms","remote":"127.0.0.1:48294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-05-06T01:18:36.370689Z","caller":"traceutil/trace.go:171","msg":"trace[409010392] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"168.112575ms","start":"2026-05-06T01:18:36.202553Z","end":"2026-05-06T01:18:36.370665Z","steps":["trace[409010392] 'process raft request' (duration: 136.68828ms)","trace[409010392] 'compare' (duration: 31.309502ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:37.502358Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.11508869s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-06T01:18:37.646755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.041255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:18:37.646854Z","caller":"traceutil/trace.go:171","msg":"trace[1191569095] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"463.072637ms","start":"2026-05-06T01:18:37.183764Z","end":"2026-05-06T01:18:37.646836Z","steps":["trace[1191569095] 'read index received' (duration: 318.759415ms)","trace[1191569095] 'applied index is now lower than readState.Index' (duration: 144.312052ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:37.646919Z","caller":"traceutil/trace.go:171","msg":"trace[2052435114] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.259693567s","start":"2026-05-06T01:18:36.387185Z","end":"2026-05-06T01:18:37.646879Z","steps":["trace[2052435114] 'process raft request' (duration: 1.115455967s)","trace[2052435114] 'compare' (duration: 143.829641ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:37.646983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.210751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:37.647015Z","caller":"traceutil/trace.go:171","msg":"trace[1271083752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"463.291844ms","start":"2026-05-06T01:18:37.183714Z","end":"2026-05-06T01:18:37.647006Z","steps":["trace[1271083752] 'agreement among raft nodes before linearized reading' (duration: 463.198761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:37.647046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:37.183697Z","time spent":"463.342784ms","remote":"127.0.0.1:55544","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-05-06T01:18:37.647091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:36.387172Z","time spent":"1.259839702s","remote":"127.0.0.1:55724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:18:37.647323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.809058ms","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-05-06T01:18:37.647402Z","caller":"traceutil/trace.go:171","msg":"trace[862144295] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"342.939471ms","start":"2026-05-06T01:18:37.304443Z","end":"2026-05-06T01:18:37.647383Z","steps":["trace[862144295] 'agreement among raft nodes before linearized reading' (duration: 342.667175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:37.647456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:37.304413Z","time spent":"343.030573ms","remote":"127.0.0.1:55804","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-05-06T01:18:37.64747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.678155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-06T01:18:37.647446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.586773ms","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-05-06T01:18:37.647517Z","caller":"traceutil/trace.go:171","msg":"trace[1836977981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"342.738777ms","start":"2026-05-06T01:18:37.304764Z","end":"2026-05-06T01:18:37.647503Z","steps":["trace[1836977981] 'agreement among raft nodes before linearized reading' (duration: 342.606444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:37.647558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:37.304758Z","time spent":"342.789938ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T01:18:37.647544Z","caller":"traceutil/trace.go:171","msg":"trace[900277801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"342.722966ms","start":"2026-05-06T01:18:37.304802Z","end":"2026-05-06T01:18:37.647525Z","steps":["trace[900277801] 'agreement among raft nodes before linearized reading' (duration: 342.504791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:37.647685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:37.304797Z","time spent":"342.87617ms","remote":"127.0.0.1:55804","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-05-06T01:18:37.647475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.629484ms","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-05-06T01:18:37.647844Z","caller":"traceutil/trace.go:171","msg":"trace[616321136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"342.999622ms","start":"2026-05-06T01:18:37.304825Z","end":"2026-05-06T01:18:37.647824Z","steps":["trace[616321136] 'agreement among raft nodes before linearized reading' (duration: 342.540742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:37.647954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:37.304821Z","time spent":"343.077514ms","remote":"127.0.0.1:55804","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":"info","ts":"2026-05-06T01:18:39.787776Z","caller":"traceutil/trace.go:171","msg":"trace[1036076703] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"109.693588ms","start":"2026-05-06T01:18:39.67805Z","end":"2026-05-06T01:18:39.787744Z","steps":["trace[1036076703] 'read index received' (duration: 108.273555ms)","trace[1036076703] 'applied index is now lower than readState.Index' (duration: 1.418763ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:39.787794Z","caller":"traceutil/trace.go:171","msg":"trace[2087834828] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"120.156778ms","start":"2026-05-06T01:18:39.667598Z","end":"2026-05-06T01:18:39.787755Z","steps":["trace[2087834828] 'process raft request' (duration: 118.777586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:39.788176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.402521ms","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-05-06T01:18:39.788272Z","caller":"traceutil/trace.go:171","msg":"trace[782962972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:666; }","duration":"109.525443ms","start":"2026-05-06T01:18:39.678723Z","end":"2026-05-06T01:18:39.788248Z","steps":["trace[782962972] 'agreement among raft nodes before linearized reading' (duration: 109.269567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:39.788286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.007832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-06T01:18:39.78836Z","caller":"traceutil/trace.go:171","msg":"trace[955112887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:666; }","duration":"109.111054ms","start":"2026-05-06T01:18:39.67923Z","end":"2026-05-06T01:18:39.788341Z","steps":["trace[955112887] 'agreement among raft nodes before linearized reading' (duration: 108.94308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:39.788765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.68854ms","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-05-06T01:18:39.788831Z","caller":"traceutil/trace.go:171","msg":"trace[718669053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:666; }","duration":"110.803313ms","start":"2026-05-06T01:18:39.678013Z","end":"2026-05-06T01:18:39.788816Z","steps":["trace[718669053] 'agreement among raft nodes before linearized reading' (duration: 109.853221ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:40.43698Z","caller":"traceutil/trace.go:171","msg":"trace[1314978115] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"143.908892ms","start":"2026-05-06T01:18:40.293046Z","end":"2026-05-06T01:18:40.436955Z","steps":["trace[1314978115] 'process raft request' (duration: 143.342759ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:42.663829Z","caller":"traceutil/trace.go:171","msg":"trace[1550944378] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"190.221745ms","start":"2026-05-06T01:18:42.473575Z","end":"2026-05-06T01:18:42.663797Z","steps":["trace[1550944378] 'process raft request' (duration: 162.192472ms)","trace[1550944378] 'compare' (duration: 27.8823ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:42.711273Z","caller":"traceutil/trace.go:171","msg":"trace[22098624] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"151.239471ms","start":"2026-05-06T01:18:42.559951Z","end":"2026-05-06T01:18:42.711191Z","steps":["trace[22098624] 'process raft request' (duration: 150.922764ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:43.038969Z","caller":"traceutil/trace.go:171","msg":"trace[1352736282] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"111.427407ms","start":"2026-05-06T01:18:42.927483Z","end":"2026-05-06T01:18:43.03891Z","steps":["trace[1352736282] 'process raft request' (duration: 110.906015ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:45.932553Z","caller":"traceutil/trace.go:171","msg":"trace[758408105] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:757; }","duration":"102.376389ms","start":"2026-05-06T01:18:45.830152Z","end":"2026-05-06T01:18:45.932529Z","steps":["trace[758408105] 'read index received' (duration: 88.521821ms)","trace[758408105] 'applied index is now lower than readState.Index' (duration: 13.853528ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:45.93265Z","caller":"traceutil/trace.go:171","msg":"trace[496863533] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"112.054671ms","start":"2026-05-06T01:18:45.820554Z","end":"2026-05-06T01:18:45.932609Z","steps":["trace[496863533] 'process raft request' (duration: 111.847176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:45.932773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.603014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-06T01:18:45.932805Z","caller":"traceutil/trace.go:171","msg":"trace[575713029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:735; }","duration":"102.655325ms","start":"2026-05-06T01:18:45.830138Z","end":"2026-05-06T01:18:45.932793Z","steps":["trace[575713029] 'agreement among raft nodes before linearized reading' (duration: 102.507492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:45.932854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.104523ms","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-05-06T01:18:45.932966Z","caller":"traceutil/trace.go:171","msg":"trace[593516721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:735; }","duration":"102.221425ms","start":"2026-05-06T01:18:45.830701Z","end":"2026-05-06T01:18:45.932922Z","steps":["trace[593516721] 'agreement among raft nodes before linearized reading' (duration: 102.024401ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:48.413982Z","caller":"traceutil/trace.go:171","msg":"trace[2008076353] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"148.62397ms","start":"2026-05-06T01:18:48.265286Z","end":"2026-05-06T01:18:48.413909Z","steps":["trace[2008076353] 'read index received' (duration: 148.213081ms)","trace[2008076353] 'applied index is now lower than readState.Index' (duration: 408.809µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:48.41404Z","caller":"traceutil/trace.go:171","msg":"trace[79009514] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"216.725284ms","start":"2026-05-06T01:18:48.197277Z","end":"2026-05-06T01:18:48.414002Z","steps":["trace[79009514] 'process raft request' (duration: 216.364595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:48.41417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.855616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:48.414235Z","caller":"traceutil/trace.go:171","msg":"trace[608874730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"148.999029ms","start":"2026-05-06T01:18:48.265217Z","end":"2026-05-06T01:18:48.414216Z","steps":["trace[608874730] 'agreement among raft nodes before linearized reading' (duration: 148.861746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:54.293837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.07786ms","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-05-06T01:18:54.293928Z","caller":"traceutil/trace.go:171","msg":"trace[952870247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:787; }","duration":"338.230553ms","start":"2026-05-06T01:18:53.955674Z","end":"2026-05-06T01:18:54.293905Z","steps":["trace[952870247] 'range keys from in-memory index tree' (duration: 337.888185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:54.293985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:53.955652Z","time spent":"338.323265ms","remote":"127.0.0.1:55804","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-05-06T01:18:54.294013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.140594ms","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-05-06T01:18:54.294067Z","caller":"traceutil/trace.go:171","msg":"trace[1372344274] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:787; }","duration":"326.224926ms","start":"2026-05-06T01:18:53.96782Z","end":"2026-05-06T01:18:54.294045Z","steps":["trace[1372344274] 'range keys from in-memory index tree' (duration: 325.996291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:54.294102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.462868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:54.294204Z","caller":"traceutil/trace.go:171","msg":"trace[1762054865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"111.596301ms","start":"2026-05-06T01:18:54.182574Z","end":"2026-05-06T01:18:54.29417Z","steps":["trace[1762054865] 'range keys from in-memory index tree' (duration: 111.354416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:54.294217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.06134ms","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-05-06T01:18:54.294247Z","caller":"traceutil/trace.go:171","msg":"trace[374743380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:787; }","duration":"308.107282ms","start":"2026-05-06T01:18:53.986129Z","end":"2026-05-06T01:18:54.294236Z","steps":["trace[374743380] 'range keys from in-memory index tree' (duration: 307.955918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:54.294271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:53.986117Z","time spent":"308.148422ms","remote":"127.0.0.1:55804","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-05-06T01:18:54.294123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:53.967799Z","time spent":"326.314619ms","remote":"127.0.0.1:55804","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-05-06T01:18:54.294389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.698279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-06T01:18:54.294426Z","caller":"traceutil/trace.go:171","msg":"trace[873949788] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:787; }","duration":"933.773131ms","start":"2026-05-06T01:18:53.360641Z","end":"2026-05-06T01:18:54.294414Z","steps":["trace[873949788] 'range keys from in-memory index tree' (duration: 933.554116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:54.294462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:53.360619Z","time spent":"933.836311ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T01:18:54.294572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.839808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-p48sf\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-06T01:18:54.294606Z","caller":"traceutil/trace.go:171","msg":"trace[2129659342] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-p48sf; range_end:; response_count:1; response_revision:787; }","duration":"759.90125ms","start":"2026-05-06T01:18:53.534694Z","end":"2026-05-06T01:18:54.294595Z","steps":["trace[2129659342] 'range keys from in-memory index tree' (duration: 759.723006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:54.294632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:53.534673Z","time spent":"759.952591ms","remote":"127.0.0.1:55724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-p48sf\" "} {"level":"warn","ts":"2026-05-06T01:18:54.293904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.880171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-06T01:18:54.294792Z","caller":"traceutil/trace.go:171","msg":"trace[2067528976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:787; }","duration":"303.848733ms","start":"2026-05-06T01:18:53.990929Z","end":"2026-05-06T01:18:54.294778Z","steps":["trace[2067528976] 'range keys from in-memory index tree' (duration: 302.663116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:54.294842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:53.990903Z","time spent":"303.923315ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T01:19:08.484809Z","caller":"traceutil/trace.go:171","msg":"trace[1105030832] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"301.095391ms","start":"2026-05-06T01:19:08.183689Z","end":"2026-05-06T01:19:08.484785Z","steps":["trace[1105030832] 'read index received' (duration: 300.860355ms)","trace[1105030832] 'applied index is now lower than readState.Index' (duration: 234.086µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:19:08.484987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.271204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:19:08.484932Z","caller":"traceutil/trace.go:171","msg":"trace[49798192] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"460.037928ms","start":"2026-05-06T01:19:08.024826Z","end":"2026-05-06T01:19:08.484864Z","steps":["trace[49798192] 'process raft request' (duration: 459.795513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:08.48566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:08.024804Z","time spent":"460.757205ms","remote":"127.0.0.1:55724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:19:08.485065Z","caller":"traceutil/trace.go:171","msg":"trace[717743872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"301.396797ms","start":"2026-05-06T01:19:08.18365Z","end":"2026-05-06T01:19:08.485047Z","steps":["trace[717743872] 'agreement among raft nodes before linearized reading' (duration: 301.237134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:08.485749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:08.183634Z","time spent":"302.095754ms","remote":"127.0.0.1:55544","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-05-06T01:19:08.620113Z","caller":"traceutil/trace.go:171","msg":"trace[860942835] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"128.271824ms","start":"2026-05-06T01:19:08.491758Z","end":"2026-05-06T01:19:08.620029Z","steps":["trace[860942835] 'process raft request' (duration: 128.000828ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:08.620407Z","caller":"traceutil/trace.go:171","msg":"trace[259577660] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"128.047649ms","start":"2026-05-06T01:19:08.492318Z","end":"2026-05-06T01:19:08.620366Z","steps":["trace[259577660] 'process raft request' (duration: 127.697881ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:08.620468Z","caller":"traceutil/trace.go:171","msg":"trace[221475746] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"125.333367ms","start":"2026-05-06T01:19:08.495093Z","end":"2026-05-06T01:19:08.620426Z","steps":["trace[221475746] 'process raft request' (duration: 125.232785ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:08.620932Z","caller":"traceutil/trace.go:171","msg":"trace[1525893043] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"128.948969ms","start":"2026-05-06T01:19:08.491971Z","end":"2026-05-06T01:19:08.620919Z","steps":["trace[1525893043] 'process raft request' (duration: 127.988338ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:08.621001Z","caller":"traceutil/trace.go:171","msg":"trace[2064195543] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"128.477979ms","start":"2026-05-06T01:19:08.492511Z","end":"2026-05-06T01:19:08.620989Z","steps":["trace[2064195543] 'process raft request' (duration: 127.62288ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:08.621235Z","caller":"traceutil/trace.go:171","msg":"trace[1404216410] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"128.600621ms","start":"2026-05-06T01:19:08.492624Z","end":"2026-05-06T01:19:08.621225Z","steps":["trace[1404216410] 'process raft request' (duration: 127.580858ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:08.621328Z","caller":"traceutil/trace.go:171","msg":"trace[271892544] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"127.856893ms","start":"2026-05-06T01:19:08.493463Z","end":"2026-05-06T01:19:08.62132Z","steps":["trace[271892544] 'process raft request' (duration: 126.795939ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:08.805261Z","caller":"traceutil/trace.go:171","msg":"trace[1449422368] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"175.231273ms","start":"2026-05-06T01:19:08.630005Z","end":"2026-05-06T01:19:08.805236Z","steps":["trace[1449422368] 'process raft request' (duration: 175.071059ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:08.807421Z","caller":"traceutil/trace.go:171","msg":"trace[1206404637] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"125.110682ms","start":"2026-05-06T01:19:08.682287Z","end":"2026-05-06T01:19:08.807398Z","steps":["trace[1206404637] 'process raft request' (duration: 124.999159ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:09.019501Z","caller":"traceutil/trace.go:171","msg":"trace[281323873] transaction","detail":"{read_only:false; number_of_response:1; response_revision:929; }","duration":"152.900139ms","start":"2026-05-06T01:19:08.866568Z","end":"2026-05-06T01:19:09.019468Z","steps":["trace[281323873] 'process raft request' (duration: 98.868089ms)","trace[281323873] 'compare' (duration: 53.763484ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:19:09.270848Z","caller":"traceutil/trace.go:171","msg":"trace[318055366] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"241.477942ms","start":"2026-05-06T01:19:09.029343Z","end":"2026-05-06T01:19:09.270821Z","steps":["trace[318055366] 'process raft request' (duration: 241.321628ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:09.440773Z","caller":"traceutil/trace.go:171","msg":"trace[1743162840] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"163.896021ms","start":"2026-05-06T01:19:09.276837Z","end":"2026-05-06T01:19:09.440733Z","steps":["trace[1743162840] 'process raft request' (duration: 118.412228ms)","trace[1743162840] 'compare' (duration: 45.196997ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:19:09.833444Z","caller":"traceutil/trace.go:171","msg":"trace[1448385017] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"190.910062ms","start":"2026-05-06T01:19:09.642508Z","end":"2026-05-06T01:19:09.833418Z","steps":["trace[1448385017] 'process raft request' (duration: 190.633665ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:19.980621Z","caller":"traceutil/trace.go:171","msg":"trace[1760005382] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"161.134668ms","start":"2026-05-06T01:19:19.819425Z","end":"2026-05-06T01:19:19.980559Z","steps":["trace[1760005382] 'process raft request' (duration: 119.838279ms)","trace[1760005382] 'compare' (duration: 41.096174ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:19:20.846158Z","caller":"traceutil/trace.go:171","msg":"trace[1882274083] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"117.21278ms","start":"2026-05-06T01:19:20.728925Z","end":"2026-05-06T01:19:20.846138Z","steps":["trace[1882274083] 'read index received' (duration: 117.015625ms)","trace[1882274083] 'applied index is now lower than readState.Index' (duration: 196.375µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:19:20.846297Z","caller":"traceutil/trace.go:171","msg":"trace[1067686122] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"268.143053ms","start":"2026-05-06T01:19:20.578141Z","end":"2026-05-06T01:19:20.846284Z","steps":["trace[1067686122] 'process raft request' (duration: 267.842536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:20.846533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.20337ms","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-05-06T01:19:20.846587Z","caller":"traceutil/trace.go:171","msg":"trace[774483799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:969; }","duration":"117.365234ms","start":"2026-05-06T01:19:20.729208Z","end":"2026-05-06T01:19:20.846574Z","steps":["trace[774483799] 'agreement among raft nodes before linearized reading' (duration: 117.159879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:20.846765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.837135ms","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-05-06T01:19:20.846848Z","caller":"traceutil/trace.go:171","msg":"trace[1220534318] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:969; }","duration":"117.923437ms","start":"2026-05-06T01:19:20.728877Z","end":"2026-05-06T01:19:20.8468Z","steps":["trace[1220534318] 'agreement among raft nodes before linearized reading' (duration: 117.786194ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:23.12352Z","caller":"traceutil/trace.go:171","msg":"trace[1288015729] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"110.499766ms","start":"2026-05-06T01:19:23.012978Z","end":"2026-05-06T01:19:23.123478Z","steps":["trace[1288015729] 'process raft request' (duration: 108.042119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:30.22292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:29.780085Z","time spent":"442.831443ms","remote":"127.0.0.1:55574","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-06T01:19:30.892157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.220306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:19:30.892273Z","caller":"traceutil/trace.go:171","msg":"trace[366139734] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"668.44882ms","start":"2026-05-06T01:19:30.223805Z","end":"2026-05-06T01:19:30.892254Z","steps":["trace[366139734] 'read index received' (duration: 498.050261ms)","trace[366139734] 'applied index is now lower than readState.Index' (duration: 170.396229ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:19:30.892355Z","caller":"traceutil/trace.go:171","msg":"trace[683350070] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"668.645244ms","start":"2026-05-06T01:19:30.223696Z","end":"2026-05-06T01:19:30.892341Z","steps":["trace[683350070] 'process raft request' (duration: 498.145562ms)","trace[683350070] 'compare' (duration: 169.838147ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:19:30.89242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:30.223679Z","time spent":"668.703286ms","remote":"127.0.0.1:55574","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-05-06T01:19:30.892719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.908132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:19:30.892971Z","caller":"traceutil/trace.go:171","msg":"trace[1055143720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1004; }","duration":"669.178278ms","start":"2026-05-06T01:19:30.223778Z","end":"2026-05-06T01:19:30.892956Z","steps":["trace[1055143720] 'agreement among raft nodes before linearized reading' (duration: 668.86171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:30.893056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:30.223767Z","time spent":"669.27795ms","remote":"127.0.0.1:55556","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-05-06T01:19:30.893806Z","caller":"traceutil/trace.go:171","msg":"trace[120918169] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"326.547075ms","start":"2026-05-06T01:19:30.567231Z","end":"2026-05-06T01:19:30.893778Z","steps":["trace[120918169] 'process raft request' (duration: 325.811598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:30.893994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:30.567207Z","time spent":"326.674207ms","remote":"127.0.0.1:55804","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-05-06T01:19:35.129694Z","caller":"traceutil/trace.go:171","msg":"trace[1797444393] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"148.106489ms","start":"2026-05-06T01:19:34.981539Z","end":"2026-05-06T01:19:35.129645Z","steps":["trace[1797444393] 'process raft request' (duration: 72.890583ms)","trace[1797444393] 'compare' (duration: 75.014172ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:19:35.276983Z","caller":"traceutil/trace.go:171","msg":"trace[785854164] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"120.016995ms","start":"2026-05-06T01:19:35.156942Z","end":"2026-05-06T01:19:35.276959Z","steps":["trace[785854164] 'read index received' (duration: 22.372183ms)","trace[785854164] 'applied index is now lower than readState.Index' (duration: 97.643962ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:19:35.277041Z","caller":"traceutil/trace.go:171","msg":"trace[2086415350] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"143.438623ms","start":"2026-05-06T01:19:35.133563Z","end":"2026-05-06T01:19:35.277002Z","steps":["trace[2086415350] 'process raft request' (duration: 96.656109ms)","trace[2086415350] 'compare' (duration: 46.63717ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:19:35.277236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.955043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-06T01:19:35.277283Z","caller":"traceutil/trace.go:171","msg":"trace[369367595] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1022; }","duration":"113.041385ms","start":"2026-05-06T01:19:35.164228Z","end":"2026-05-06T01:19:35.277269Z","steps":["trace[369367595] 'agreement among raft nodes before linearized reading' (duration: 112.881991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:35.277457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.518956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-06T01:19:35.277504Z","caller":"traceutil/trace.go:171","msg":"trace[611072949] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1022; }","duration":"120.586688ms","start":"2026-05-06T01:19:35.156905Z","end":"2026-05-06T01:19:35.277492Z","steps":["trace[611072949] 'agreement among raft nodes before linearized reading' (duration: 120.144338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:39.537118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.385036ms","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-05-06T01:19:39.537249Z","caller":"traceutil/trace.go:171","msg":"trace[1553690073] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1033; }","duration":"172.525159ms","start":"2026-05-06T01:19:39.364702Z","end":"2026-05-06T01:19:39.537227Z","steps":["trace[1553690073] 'range keys from in-memory index tree' (duration: 172.368456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:39.537247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.190377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:19:39.537392Z","caller":"traceutil/trace.go:171","msg":"trace[1534878226] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"241.164824ms","start":"2026-05-06T01:19:39.296204Z","end":"2026-05-06T01:19:39.537369Z","steps":["trace[1534878226] 'read index received' (duration: 117.800493ms)","trace[1534878226] 'applied index is now lower than readState.Index' (duration: 123.361551ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:19:39.537399Z","caller":"traceutil/trace.go:171","msg":"trace[766815888] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"243.909588ms","start":"2026-05-06T01:19:39.293465Z","end":"2026-05-06T01:19:39.537375Z","steps":["trace[766815888] 'process raft request' (duration: 120.517566ms)","trace[766815888] 'compare' (duration: 123.094105ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:19:39.537539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.327389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:19:39.53758Z","caller":"traceutil/trace.go:171","msg":"trace[1415048984] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1034; }","duration":"241.414251ms","start":"2026-05-06T01:19:39.296153Z","end":"2026-05-06T01:19:39.537567Z","steps":["trace[1415048984] 'agreement among raft nodes before linearized reading' (duration: 241.338619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:39.537822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.400028ms","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-05-06T01:19:39.537872Z","caller":"traceutil/trace.go:171","msg":"trace[1082877290] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1034; }","duration":"213.497201ms","start":"2026-05-06T01:19:39.324363Z","end":"2026-05-06T01:19:39.53786Z","steps":["trace[1082877290] 'agreement among raft nodes before linearized reading' (duration: 213.338676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:39.538071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.572007ms","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-05-06T01:19:39.538134Z","caller":"traceutil/trace.go:171","msg":"trace[859510376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1034; }","duration":"208.663279ms","start":"2026-05-06T01:19:39.329456Z","end":"2026-05-06T01:19:39.53812Z","steps":["trace[859510376] 'agreement among raft nodes before linearized reading' (duration: 208.486625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:39.840824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.048314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:19:39.841053Z","caller":"traceutil/trace.go:171","msg":"trace[1876086163] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"299.117675ms","start":"2026-05-06T01:19:39.541871Z","end":"2026-05-06T01:19:39.840988Z","steps":["trace[1876086163] 'process raft request' (duration: 104.802265ms)","trace[1876086163] 'compare' (duration: 193.900681ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:19:52.040322Z","caller":"traceutil/trace.go:171","msg":"trace[1930491944] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"139.222529ms","start":"2026-05-06T01:19:51.901072Z","end":"2026-05-06T01:19:52.040294Z","steps":["trace[1930491944] 'process raft request' (duration: 139.022744ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:55.649317Z","caller":"traceutil/trace.go:171","msg":"trace[104090979] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"451.595365ms","start":"2026-05-06T01:19:55.197693Z","end":"2026-05-06T01:19:55.649289Z","steps":["trace[104090979] 'process raft request' (duration: 451.427211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:55.649488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:55.197664Z","time spent":"451.738838ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:19:55.896149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.917689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:19:55.896536Z","caller":"traceutil/trace.go:171","msg":"trace[1323205372] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"608.625522ms","start":"2026-05-06T01:19:55.287874Z","end":"2026-05-06T01:19:55.8965Z","steps":["trace[1323205372] 'process raft request' (duration: 450.232523ms)","trace[1323205372] 'compare' (duration: 157.704003ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:19:55.896727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:55.287847Z","time spent":"608.767636ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:19:56.150125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.726106ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-06T01:19:56.150355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.123922ms","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-05-06T01:19:56.150623Z","caller":"traceutil/trace.go:171","msg":"trace[835995127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1086; }","duration":"704.41986ms","start":"2026-05-06T01:19:55.446184Z","end":"2026-05-06T01:19:56.150604Z","steps":["trace[835995127] 'agreement among raft nodes before linearized reading' (duration: 704.02007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:56.150662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:55.446169Z","time spent":"704.485151ms","remote":"127.0.0.1:55804","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-05-06T01:19:56.150361Z","caller":"traceutil/trace.go:171","msg":"trace[1370153010] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1124; }","duration":"704.102652ms","start":"2026-05-06T01:19:55.446219Z","end":"2026-05-06T01:19:56.150322Z","steps":["trace[1370153010] 'read index received' (duration: 202.923609ms)","trace[1370153010] 'applied index is now lower than readState.Index' (duration: 501.05639ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:19:56.151069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.589224ms","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-05-06T01:19:56.151094Z","caller":"traceutil/trace.go:171","msg":"trace[1524152042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1086; }","duration":"431.656366ms","start":"2026-05-06T01:19:55.71943Z","end":"2026-05-06T01:19:56.151086Z","steps":["trace[1524152042] 'agreement among raft nodes before linearized reading' (duration: 431.579164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:56.151112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:55.719405Z","time spent":"431.703076ms","remote":"127.0.0.1:55804","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-05-06T01:19:56.151089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.491972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-06T01:19:56.151176Z","caller":"traceutil/trace.go:171","msg":"trace[335879839] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1086; }","duration":"541.639415ms","start":"2026-05-06T01:19:55.609521Z","end":"2026-05-06T01:19:56.151161Z","steps":["trace[335879839] 'agreement among raft nodes before linearized reading' (duration: 541.306958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:56.151256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:55.609501Z","time spent":"541.740457ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-06T01:19:56.489613Z","caller":"traceutil/trace.go:171","msg":"trace[1325521361] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"334.709233ms","start":"2026-05-06T01:19:56.15477Z","end":"2026-05-06T01:19:56.489479Z","steps":["trace[1325521361] 'process raft request' (duration: 334.514378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:56.489932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:56.154747Z","time spent":"335.05231ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:19:56.529787Z","caller":"traceutil/trace.go:171","msg":"trace[1256869863] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"374.49791ms","start":"2026-05-06T01:19:56.155264Z","end":"2026-05-06T01:19:56.529762Z","steps":["trace[1256869863] 'process raft request' (duration: 374.399668ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:56.530038Z","caller":"traceutil/trace.go:171","msg":"trace[560685674] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"375.008321ms","start":"2026-05-06T01:19:56.155009Z","end":"2026-05-06T01:19:56.530017Z","steps":["trace[560685674] 'process raft request' (duration: 374.52432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:56.530189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:56.155247Z","time spent":"374.655083ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:19:56.530377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:56.154999Z","time spent":"375.243447ms","remote":"127.0.0.1:55804","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-05-06T01:19:56.530783Z","caller":"traceutil/trace.go:171","msg":"trace[1945505731] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1127; }","duration":"317.464325ms","start":"2026-05-06T01:19:56.213304Z","end":"2026-05-06T01:19:56.530769Z","steps":["trace[1945505731] 'read index received' (duration: 275.958819ms)","trace[1945505731] 'applied index is now lower than readState.Index' (duration: 41.504276ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:19:56.530852Z","caller":"traceutil/trace.go:171","msg":"trace[1298416374] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"374.98914ms","start":"2026-05-06T01:19:56.155846Z","end":"2026-05-06T01:19:56.530835Z","steps":["trace[1298416374] 'process raft request' (duration: 373.868234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:56.530944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:56.155838Z","time spent":"375.053432ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:19:56.530988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.681642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:19:56.531152Z","caller":"traceutil/trace.go:171","msg":"trace[443684904] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1090; }","duration":"317.807715ms","start":"2026-05-06T01:19:56.213247Z","end":"2026-05-06T01:19:56.531055Z","steps":["trace[443684904] 'agreement among raft nodes before linearized reading' (duration: 317.692882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:56.531171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.81392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:19:56.531201Z","caller":"traceutil/trace.go:171","msg":"trace[1777511986] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1090; }","duration":"296.877151ms","start":"2026-05-06T01:19:56.234315Z","end":"2026-05-06T01:19:56.531192Z","steps":["trace[1777511986] 'agreement among raft nodes before linearized reading' (duration: 296.82325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:56.531209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:56.213226Z","time spent":"317.967138ms","remote":"127.0.0.1:55790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:19:58.307043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138339846782543,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T01:19:58.627585Z","caller":"traceutil/trace.go:171","msg":"trace[891135615] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"969.560663ms","start":"2026-05-06T01:19:57.658001Z","end":"2026-05-06T01:19:58.627562Z","steps":["trace[891135615] 'process raft request' (duration: 969.42483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:58.627757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:57.657989Z","time spent":"969.668165ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:19:58.687065Z","caller":"traceutil/trace.go:171","msg":"trace[931959215] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1131; }","duration":"880.67998ms","start":"2026-05-06T01:19:57.806356Z","end":"2026-05-06T01:19:58.687036Z","steps":["trace[931959215] 'read index received' (duration: 821.073006ms)","trace[931959215] 'applied index is now lower than readState.Index' (duration: 59.606014ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:19:58.687215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.842043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T01:19:58.687258Z","caller":"traceutil/trace.go:171","msg":"trace[954557234] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1092; }","duration":"880.937296ms","start":"2026-05-06T01:19:57.806314Z","end":"2026-05-06T01:19:58.687252Z","steps":["trace[954557234] 'agreement among raft nodes before linearized reading' (duration: 880.850054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:58.687287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:57.806297Z","time spent":"880.983506ms","remote":"127.0.0.1:55644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:19:58.687489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.550204ms","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-05-06T01:19:58.687513Z","caller":"traceutil/trace.go:171","msg":"trace[560783268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1092; }","duration":"192.609005ms","start":"2026-05-06T01:19:58.494898Z","end":"2026-05-06T01:19:58.687507Z","steps":["trace[560783268] 'agreement among raft nodes before linearized reading' (duration: 192.528614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:58.6875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.89007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-06T01:19:58.687546Z","caller":"traceutil/trace.go:171","msg":"trace[1246125147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1092; }","duration":"152.958592ms","start":"2026-05-06T01:19:58.534575Z","end":"2026-05-06T01:19:58.687534Z","steps":["trace[1246125147] 'agreement among raft nodes before linearized reading' (duration: 152.752767ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:58.687147Z","caller":"traceutil/trace.go:171","msg":"trace[654151975] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"785.360836ms","start":"2026-05-06T01:19:57.90176Z","end":"2026-05-06T01:19:58.687121Z","steps":["trace[654151975] 'process raft request' (duration: 765.14307ms)","trace[654151975] 'compare' (duration: 20.025351ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:19:58.687899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:57.901737Z","time spent":"786.01937ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:19:58.688016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.481354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-06T01:19:58.688166Z","caller":"traceutil/trace.go:171","msg":"trace[1040058505] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1092; }","duration":"153.752151ms","start":"2026-05-06T01:19:58.534395Z","end":"2026-05-06T01:19:58.688147Z","steps":["trace[1040058505] 'agreement among raft nodes before linearized reading' (duration: 152.998553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:58.687394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.361088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:19:58.688979Z","caller":"traceutil/trace.go:171","msg":"trace[1207026858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1092; }","duration":"505.971586ms","start":"2026-05-06T01:19:58.18298Z","end":"2026-05-06T01:19:58.688952Z","steps":["trace[1207026858] 'agreement among raft nodes before linearized reading' (duration: 504.335787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:58.689163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:58.182966Z","time spent":"506.182121ms","remote":"127.0.0.1:55544","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-05-06T01:19:58.688529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.588338ms","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-05-06T01:19:58.690073Z","caller":"traceutil/trace.go:171","msg":"trace[997505340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1092; }","duration":"155.139553ms","start":"2026-05-06T01:19:58.534898Z","end":"2026-05-06T01:19:58.690037Z","steps":["trace[997505340] 'agreement among raft nodes before linearized reading' (duration: 152.526752ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:58.935621Z","caller":"traceutil/trace.go:171","msg":"trace[532424841] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"243.543746ms","start":"2026-05-06T01:19:58.692039Z","end":"2026-05-06T01:19:58.935583Z","steps":["trace[532424841] 'process raft request' (duration: 162.664751ms)","trace[532424841] 'compare' (duration: 80.539577ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:19:58.941724Z","caller":"traceutil/trace.go:171","msg":"trace[977091141] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"247.935818ms","start":"2026-05-06T01:19:58.693767Z","end":"2026-05-06T01:19:58.941703Z","steps":["trace[977091141] 'process raft request' (duration: 247.901227ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:58.941873Z","caller":"traceutil/trace.go:171","msg":"trace[1226012424] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"249.439944ms","start":"2026-05-06T01:19:58.692391Z","end":"2026-05-06T01:19:58.941831Z","steps":["trace[1226012424] 'process raft request' (duration: 249.137207ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:58.941946Z","caller":"traceutil/trace.go:171","msg":"trace[1080728333] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"248.219745ms","start":"2026-05-06T01:19:58.693708Z","end":"2026-05-06T01:19:58.941928Z","steps":["trace[1080728333] 'process raft request' (duration: 247.925528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:00.476402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.76709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:20:00.476504Z","caller":"traceutil/trace.go:171","msg":"trace[1582215025] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1097; }","duration":"530.917964ms","start":"2026-05-06T01:19:59.945564Z","end":"2026-05-06T01:20:00.476482Z","steps":["trace[1582215025] 'count revisions from in-memory index tree' (duration: 530.668367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:00.476567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:19:59.945549Z","time spent":"530.995945ms","remote":"127.0.0.1:55826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:20:00.47668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.603261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:20:00.476738Z","caller":"traceutil/trace.go:171","msg":"trace[664757112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1097; }","duration":"292.718603ms","start":"2026-05-06T01:20:00.184004Z","end":"2026-05-06T01:20:00.476723Z","steps":["trace[664757112] 'range keys from in-memory index tree' (duration: 292.463237ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:01.068129Z","caller":"traceutil/trace.go:171","msg":"trace[1917027062] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"432.244159ms","start":"2026-05-06T01:20:00.635854Z","end":"2026-05-06T01:20:01.068098Z","steps":["trace[1917027062] 'process raft request' (duration: 432.070245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:01.068363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:00.635833Z","time spent":"432.447803ms","remote":"127.0.0.1:55804","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-05-06T01:20:02.073828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.104359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-05-06T01:20:02.074854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.293299678s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:20:02.074925Z","caller":"traceutil/trace.go:171","msg":"trace[1914043098] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1099; }","duration":"1.29340211s","start":"2026-05-06T01:20:00.781495Z","end":"2026-05-06T01:20:02.074897Z","steps":["trace[1914043098] 'agreement among raft nodes before linearized reading' (duration: 1.293308018s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:02.075013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:00.781473Z","time spent":"1.293512343s","remote":"127.0.0.1:48266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-05-06T01:20:02.074766Z","caller":"traceutil/trace.go:171","msg":"trace[683266825] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"1.381165027s","start":"2026-05-06T01:20:00.693577Z","end":"2026-05-06T01:20:02.074742Z","steps":["trace[683266825] 'process raft request' (duration: 457.054594ms)","trace[683266825] 'compare' (duration: 922.549736ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:20:02.075438Z","caller":"traceutil/trace.go:171","msg":"trace[590031672] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1139; }","duration":"1.293103262s","start":"2026-05-06T01:20:00.781547Z","end":"2026-05-06T01:20:02.07465Z","steps":["trace[590031672] 'read index received' (duration: 286.385534ms)","trace[590031672] 'applied index is now lower than readState.Index' (duration: 1.006716038s)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:20:02.075525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:00.693555Z","time spent":"1.381887045s","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:20:02.251705Z","caller":"traceutil/trace.go:171","msg":"trace[1320687952] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"176.157588ms","start":"2026-05-06T01:20:02.075523Z","end":"2026-05-06T01:20:02.251681Z","steps":["trace[1320687952] 'read index received' (duration: 170.374892ms)","trace[1320687952] 'applied index is now lower than readState.Index' (duration: 5.781506ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:20:02.251823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007872056s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T01:20:02.251866Z","caller":"traceutil/trace.go:171","msg":"trace[400609548] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"835.568499ms","start":"2026-05-06T01:20:01.41626Z","end":"2026-05-06T01:20:02.251829Z","steps":["trace[400609548] 'process raft request' (duration: 829.728071ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:02.251942Z","caller":"traceutil/trace.go:171","msg":"trace[1788412235] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1100; }","duration":"1.00807095s","start":"2026-05-06T01:20:01.243848Z","end":"2026-05-06T01:20:02.251919Z","steps":["trace[1788412235] 'agreement among raft nodes before linearized reading' (duration: 1.007877215s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:02.25199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305592107s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-06T01:20:02.251978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304977893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-06T01:20:02.252025Z","caller":"traceutil/trace.go:171","msg":"trace[803378569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1100; }","duration":"1.305675839s","start":"2026-05-06T01:20:00.946339Z","end":"2026-05-06T01:20:02.252015Z","steps":["trace[803378569] 'agreement among raft nodes before linearized reading' (duration: 1.305478255s)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:02.252043Z","caller":"traceutil/trace.go:171","msg":"trace[859496684] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1100; }","duration":"1.305086605s","start":"2026-05-06T01:20:00.946942Z","end":"2026-05-06T01:20:02.252028Z","steps":["trace[859496684] 'agreement among raft nodes before linearized reading' (duration: 1.304816989s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:02.252045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:01.416234Z","time spent":"835.728112ms","remote":"127.0.0.1:55804","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-05-06T01:20:02.252071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:00.946327Z","time spent":"1.305735s","remote":"127.0.0.1:55804","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-05-06T01:20:02.252067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304803409s","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-05-06T01:20:02.252086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:00.946924Z","time spent":"1.305149937s","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T01:20:02.252087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.033839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:20:02.252115Z","caller":"traceutil/trace.go:171","msg":"trace[1347988181] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1100; }","duration":"1.30487542s","start":"2026-05-06T01:20:00.947227Z","end":"2026-05-06T01:20:02.252103Z","steps":["trace[1347988181] 'agreement among raft nodes before linearized reading' (duration: 1.304763298s)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:02.252131Z","caller":"traceutil/trace.go:171","msg":"trace[44695950] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1100; }","duration":"368.112939ms","start":"2026-05-06T01:20:01.884004Z","end":"2026-05-06T01:20:02.252117Z","steps":["trace[44695950] 'agreement among raft nodes before linearized reading' (duration: 368.047988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:02.252205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:01.883979Z","time spent":"368.211812ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:20:02.252021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:01.243825Z","time spent":"1.008161962s","remote":"127.0.0.1:55984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:20:02.252147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:00.947215Z","time spent":"1.304924762s","remote":"127.0.0.1:55804","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-05-06T01:20:02.252981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.312414497s","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-05-06T01:20:02.25304Z","caller":"traceutil/trace.go:171","msg":"trace[741498571] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1100; }","duration":"1.31251203s","start":"2026-05-06T01:20:00.940512Z","end":"2026-05-06T01:20:02.253024Z","steps":["trace[741498571] 'agreement among raft nodes before linearized reading' (duration: 1.31167628s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:02.253077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:00.940493Z","time spent":"1.312573191s","remote":"127.0.0.1:55804","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-05-06T01:20:12.42273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.103544ms","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-05-06T01:20:12.42279Z","caller":"traceutil/trace.go:171","msg":"trace[1999711667] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1183; }","duration":"116.213166ms","start":"2026-05-06T01:20:12.306565Z","end":"2026-05-06T01:20:12.422778Z","steps":["trace[1999711667] 'range keys from in-memory index tree' (duration: 115.976301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:12.422859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.650241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:20:12.422918Z","caller":"traceutil/trace.go:171","msg":"trace[1194374824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1183; }","duration":"241.732572ms","start":"2026-05-06T01:20:12.181172Z","end":"2026-05-06T01:20:12.422904Z","steps":["trace[1194374824] 'range keys from in-memory index tree' (duration: 241.60894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:12.423274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.417792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-06T01:20:12.423555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.703493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-06T01:20:12.423585Z","caller":"traceutil/trace.go:171","msg":"trace[247921892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1184; }","duration":"109.775566ms","start":"2026-05-06T01:20:12.313802Z","end":"2026-05-06T01:20:12.423577Z","steps":["trace[247921892] 'agreement among raft nodes before linearized reading' (duration: 109.642983ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:12.42354Z","caller":"traceutil/trace.go:171","msg":"trace[1981274621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1183; }","duration":"116.73949ms","start":"2026-05-06T01:20:12.306779Z","end":"2026-05-06T01:20:12.423518Z","steps":["trace[1981274621] 'range keys from in-memory index tree' (duration: 116.179086ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:12.423424Z","caller":"traceutil/trace.go:171","msg":"trace[1850428900] linearizableReadLoop","detail":"{readStateIndex:1230; appliedIndex:1229; }","duration":"109.55038ms","start":"2026-05-06T01:20:12.313863Z","end":"2026-05-06T01:20:12.423413Z","steps":["trace[1850428900] 'read index received' (duration: 108.500905ms)","trace[1850428900] 'applied index is now lower than readState.Index' (duration: 1.048715ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:20:12.423878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.059551ms","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-05-06T01:20:12.423916Z","caller":"traceutil/trace.go:171","msg":"trace[959697227] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1184; }","duration":"104.114792ms","start":"2026-05-06T01:20:12.319795Z","end":"2026-05-06T01:20:12.42391Z","steps":["trace[959697227] 'agreement among raft nodes before linearized reading' (duration: 104.04314ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:12.423732Z","caller":"traceutil/trace.go:171","msg":"trace[739620496] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"115.202363ms","start":"2026-05-06T01:20:12.308519Z","end":"2026-05-06T01:20:12.423722Z","steps":["trace[739620496] 'process raft request' (duration: 113.909472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:14.681839Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138339846782765,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T01:20:14.978242Z","caller":"traceutil/trace.go:171","msg":"trace[351434392] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"798.583356ms","start":"2026-05-06T01:20:14.179632Z","end":"2026-05-06T01:20:14.978215Z","steps":["trace[351434392] 'process raft request' (duration: 798.33279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:14.978409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:14.179613Z","time spent":"798.71871ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:20:16.02874Z","caller":"traceutil/trace.go:171","msg":"trace[1647020883] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"1.486771083s","start":"2026-05-06T01:20:14.541942Z","end":"2026-05-06T01:20:16.028713Z","steps":["trace[1647020883] 'process raft request' (duration: 1.486494806s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:16.029094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:14.541921Z","time spent":"1.486874566s","remote":"127.0.0.1:55804","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-05-06T01:20:16.031685Z","caller":"traceutil/trace.go:171","msg":"trace[772147352] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1235; }","duration":"1.849939907s","start":"2026-05-06T01:20:14.181726Z","end":"2026-05-06T01:20:16.031666Z","steps":["trace[772147352] 'read index received' (duration: 796.390676ms)","trace[772147352] 'applied index is now lower than readState.Index' (duration: 1.053547721s)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:20:16.031873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.527206235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:20:16.031912Z","caller":"traceutil/trace.go:171","msg":"trace[150089050] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1191; }","duration":"1.527275797s","start":"2026-05-06T01:20:14.504626Z","end":"2026-05-06T01:20:16.031902Z","steps":["trace[150089050] 'agreement among raft nodes before linearized reading' (duration: 1.527230066s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:16.031934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:14.504608Z","time spent":"1.527320798s","remote":"127.0.0.1:48084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:20:16.032011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.591740185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-06T01:20:16.031999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.600166154s","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-05-06T01:20:16.032052Z","caller":"traceutil/trace.go:171","msg":"trace[1066776171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1191; }","duration":"1.591801826s","start":"2026-05-06T01:20:14.440239Z","end":"2026-05-06T01:20:16.032041Z","steps":["trace[1066776171] 'agreement among raft nodes before linearized reading' (duration: 1.591588771s)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:16.032072Z","caller":"traceutil/trace.go:171","msg":"trace[1492766204] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1191; }","duration":"1.600289977s","start":"2026-05-06T01:20:14.431763Z","end":"2026-05-06T01:20:16.032053Z","steps":["trace[1492766204] 'agreement among raft nodes before linearized reading' (duration: 1.600044421s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:16.032085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:14.440232Z","time spent":"1.591844807s","remote":"127.0.0.1:55804","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-05-06T01:20:16.031808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.85007671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T01:20:16.032084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.595141326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-06T01:20:16.032112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:14.431725Z","time spent":"1.600378489s","remote":"127.0.0.1:55804","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":"info","ts":"2026-05-06T01:20:16.032126Z","caller":"traceutil/trace.go:171","msg":"trace[2041821344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1191; }","duration":"1.850397707s","start":"2026-05-06T01:20:14.18172Z","end":"2026-05-06T01:20:16.032117Z","steps":["trace[2041821344] 'agreement among raft nodes before linearized reading' (duration: 1.850036499s)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:16.03214Z","caller":"traceutil/trace.go:171","msg":"trace[407289888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1191; }","duration":"1.595226818s","start":"2026-05-06T01:20:14.436899Z","end":"2026-05-06T01:20:16.032126Z","steps":["trace[407289888] 'agreement among raft nodes before linearized reading' (duration: 1.594978413s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:16.032169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:14.436888Z","time spent":"1.595274999s","remote":"127.0.0.1:55804","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-05-06T01:20:16.032202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.939781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-05-06T01:20:16.032181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593308322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-06T01:20:16.032223Z","caller":"traceutil/trace.go:171","msg":"trace[1696445335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1191; }","duration":"901.996992ms","start":"2026-05-06T01:20:15.130219Z","end":"2026-05-06T01:20:16.032216Z","steps":["trace[1696445335] 'agreement among raft nodes before linearized reading' (duration: 901.635224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:16.032242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:15.130196Z","time spent":"902.041713ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T01:20:16.032254Z","caller":"traceutil/trace.go:171","msg":"trace[339998667] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1191; }","duration":"1.593406295s","start":"2026-05-06T01:20:14.43883Z","end":"2026-05-06T01:20:16.032237Z","steps":["trace[339998667] 'agreement among raft nodes before linearized reading' (duration: 1.593008236s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:16.032295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:14.438823Z","time spent":"1.593463286s","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T01:20:16.032318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:14.181661Z","time spent":"1.850486419s","remote":"127.0.0.1:55556","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-05-06T01:20:30.933782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.970671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:20:30.933879Z","caller":"traceutil/trace.go:171","msg":"trace[2012073716] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1294; }","duration":"764.821941ms","start":"2026-05-06T01:20:30.16904Z","end":"2026-05-06T01:20:30.933862Z","steps":["trace[2012073716] 'read index received' (duration: 48.599665ms)","trace[2012073716] 'applied index is now lower than readState.Index' (duration: 716.221096ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:20:30.933965Z","caller":"traceutil/trace.go:171","msg":"trace[918499501] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"855.036546ms","start":"2026-05-06T01:20:30.078917Z","end":"2026-05-06T01:20:30.933953Z","steps":["trace[918499501] 'process raft request' (duration: 138.79958ms)","trace[918499501] 'compare' (duration: 715.279994ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:20:30.934024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:30.078898Z","time spent":"855.097207ms","remote":"127.0.0.1:55574","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-05-06T01:20:30.934322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.290053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-06T01:20:30.934361Z","caller":"traceutil/trace.go:171","msg":"trace[1843025949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1245; }","duration":"765.328004ms","start":"2026-05-06T01:20:30.169023Z","end":"2026-05-06T01:20:30.934351Z","steps":["trace[1843025949] 'agreement among raft nodes before linearized reading' (duration: 765.17915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:30.93439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:30.168998Z","time spent":"765.385804ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T01:20:30.934586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.62626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-06T01:20:30.934619Z","caller":"traceutil/trace.go:171","msg":"trace[915354835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1245; }","duration":"759.687852ms","start":"2026-05-06T01:20:30.174923Z","end":"2026-05-06T01:20:30.934611Z","steps":["trace[915354835] 'agreement among raft nodes before linearized reading' (duration: 759.605109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:30.934644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:30.174912Z","time spent":"759.726682ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T01:20:30.934873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.130362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:20:30.934903Z","caller":"traceutil/trace.go:171","msg":"trace[1688664748] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1245; }","duration":"343.199613ms","start":"2026-05-06T01:20:30.591693Z","end":"2026-05-06T01:20:30.934893Z","steps":["trace[1688664748] 'agreement among raft nodes before linearized reading' (duration: 343.151362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:30.93493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:30.59167Z","time spent":"343.252324ms","remote":"127.0.0.1:55662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:20:30.935086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.483616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-06T01:20:30.935141Z","caller":"traceutil/trace.go:171","msg":"trace[908677168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1245; }","duration":"759.568058ms","start":"2026-05-06T01:20:30.175559Z","end":"2026-05-06T01:20:30.935128Z","steps":["trace[908677168] 'agreement among raft nodes before linearized reading' (duration: 759.397224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:30.935133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.026195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-06T01:20:30.935168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:30.17555Z","time spent":"759.611888ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T01:20:30.935164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.524847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T01:20:30.935197Z","caller":"traceutil/trace.go:171","msg":"trace[2117460860] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1245; }","duration":"759.119058ms","start":"2026-05-06T01:20:30.176062Z","end":"2026-05-06T01:20:30.935181Z","steps":["trace[2117460860] 'agreement among raft nodes before linearized reading' (duration: 758.904313ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:30.935249Z","caller":"traceutil/trace.go:171","msg":"trace[1575146153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1245; }","duration":"759.6432ms","start":"2026-05-06T01:20:30.175586Z","end":"2026-05-06T01:20:30.935229Z","steps":["trace[1575146153] 'agreement among raft nodes before linearized reading' (duration: 759.393544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:30.935255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:30.176053Z","time spent":"759.192279ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T01:20:30.935294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:30.175574Z","time spent":"759.710861ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T01:20:31.158612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.228856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:20:31.158852Z","caller":"traceutil/trace.go:171","msg":"trace[510155472] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"218.512306ms","start":"2026-05-06T01:20:30.940307Z","end":"2026-05-06T01:20:31.158819Z","steps":["trace[510155472] 'process raft request' (duration: 107.968842ms)","trace[510155472] 'compare' (duration: 110.058581ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:20:31.158905Z","caller":"traceutil/trace.go:171","msg":"trace[1484880098] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"218.541587ms","start":"2026-05-06T01:20:30.940336Z","end":"2026-05-06T01:20:31.158878Z","steps":["trace[1484880098] 'process raft request' (duration: 218.393343ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:31.158934Z","caller":"traceutil/trace.go:171","msg":"trace[1502208038] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"215.139867ms","start":"2026-05-06T01:20:30.943771Z","end":"2026-05-06T01:20:31.15891Z","steps":["trace[1502208038] 'process raft request' (duration: 215.021664ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:31.159136Z","caller":"traceutil/trace.go:171","msg":"trace[1345845189] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"215.27045ms","start":"2026-05-06T01:20:30.943827Z","end":"2026-05-06T01:20:31.159097Z","steps":["trace[1345845189] 'process raft request' (duration: 215.030674ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:31.159159Z","caller":"traceutil/trace.go:171","msg":"trace[56743629] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1295; }","duration":"218.699121ms","start":"2026-05-06T01:20:30.94043Z","end":"2026-05-06T01:20:31.159129Z","steps":["trace[56743629] 'read index received' (duration: 13.238041ms)","trace[56743629] 'applied index is now lower than readState.Index' (duration: 205.4599ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:20:31.159315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.635395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:20:31.159358Z","caller":"traceutil/trace.go:171","msg":"trace[75274002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1251; }","duration":"197.715877ms","start":"2026-05-06T01:20:30.961631Z","end":"2026-05-06T01:20:31.159347Z","steps":["trace[75274002] 'agreement among raft nodes before linearized reading' (duration: 197.637935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:31.159373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.921826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-06T01:20:31.159443Z","caller":"traceutil/trace.go:171","msg":"trace[1489938494] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1251; }","duration":"219.033498ms","start":"2026-05-06T01:20:30.940393Z","end":"2026-05-06T01:20:31.159427Z","steps":["trace[1489938494] 'agreement among raft nodes before linearized reading' (duration: 218.818743ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:32.805817Z","caller":"traceutil/trace.go:171","msg":"trace[420437973] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"171.851387ms","start":"2026-05-06T01:20:32.633944Z","end":"2026-05-06T01:20:32.805795Z","steps":["trace[420437973] 'process raft request' (duration: 171.55507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:33.226482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.814681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-06T01:20:33.226562Z","caller":"traceutil/trace.go:171","msg":"trace[1202216350] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1259; }","duration":"268.935783ms","start":"2026-05-06T01:20:32.95761Z","end":"2026-05-06T01:20:33.226545Z","steps":["trace[1202216350] 'range keys from in-memory index tree' (duration: 268.665948ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:33.276537Z","caller":"traceutil/trace.go:171","msg":"trace[333589774] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"110.82827ms","start":"2026-05-06T01:20:33.165689Z","end":"2026-05-06T01:20:33.276517Z","steps":["trace[333589774] 'process raft request' (duration: 110.717866ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:33.430107Z","caller":"traceutil/trace.go:171","msg":"trace[1149813920] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"199.637681ms","start":"2026-05-06T01:20:33.230447Z","end":"2026-05-06T01:20:33.430084Z","steps":["trace[1149813920] 'process raft request' (duration: 199.577839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:33.430276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.312987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:20:33.430277Z","caller":"traceutil/trace.go:171","msg":"trace[578024172] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"200.616904ms","start":"2026-05-06T01:20:33.229645Z","end":"2026-05-06T01:20:33.430262Z","steps":["trace[578024172] 'process raft request' (duration: 188.609091ms)","trace[578024172] 'compare' (duration: 11.591383ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:20:33.430317Z","caller":"traceutil/trace.go:171","msg":"trace[1440626314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1265; }","duration":"152.423019ms","start":"2026-05-06T01:20:33.277886Z","end":"2026-05-06T01:20:33.430309Z","steps":["trace[1440626314] 'agreement among raft nodes before linearized reading' (duration: 152.321437ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:33.43031Z","caller":"traceutil/trace.go:171","msg":"trace[985622715] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"200.222895ms","start":"2026-05-06T01:20:33.230051Z","end":"2026-05-06T01:20:33.430274Z","steps":["trace[985622715] 'process raft request' (duration: 199.938829ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:33.430474Z","caller":"traceutil/trace.go:171","msg":"trace[854402474] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"199.823586ms","start":"2026-05-06T01:20:33.230643Z","end":"2026-05-06T01:20:33.430466Z","steps":["trace[854402474] 'process raft request' (duration: 199.414926ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:33.430165Z","caller":"traceutil/trace.go:171","msg":"trace[1534319793] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1310; }","duration":"152.186794ms","start":"2026-05-06T01:20:33.277957Z","end":"2026-05-06T01:20:33.430144Z","steps":["trace[1534319793] 'read index received' (duration: 140.312085ms)","trace[1534319793] 'applied index is now lower than readState.Index' (duration: 11.873639ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:20:33.430195Z","caller":"traceutil/trace.go:171","msg":"trace[445482085] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"200.296616ms","start":"2026-05-06T01:20:33.229858Z","end":"2026-05-06T01:20:33.430154Z","steps":["trace[445482085] 'process raft request' (duration: 200.091832ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:34.465265Z","caller":"traceutil/trace.go:171","msg":"trace[50990055] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1316; }","duration":"281.771677ms","start":"2026-05-06T01:20:34.183475Z","end":"2026-05-06T01:20:34.465246Z","steps":["trace[50990055] 'read index received' (duration: 281.574253ms)","trace[50990055] 'applied index is now lower than readState.Index' (duration: 196.754µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:20:34.465351Z","caller":"traceutil/trace.go:171","msg":"trace[1799027065] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"355.289799ms","start":"2026-05-06T01:20:34.110053Z","end":"2026-05-06T01:20:34.465343Z","steps":["trace[1799027065] 'process raft request' (duration: 355.079514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:34.465425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:34.110017Z","time spent":"355.35279ms","remote":"127.0.0.1:55716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5499,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:20:34.465519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.055174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:20:34.465544Z","caller":"traceutil/trace.go:171","msg":"trace[1384691595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1267; }","duration":"282.113745ms","start":"2026-05-06T01:20:34.183424Z","end":"2026-05-06T01:20:34.465537Z","steps":["trace[1384691595] 'agreement among raft nodes before linearized reading' (duration: 282.077164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:35.011625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.985659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:20:35.011959Z","caller":"traceutil/trace.go:171","msg":"trace[1542824377] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"143.376517ms","start":"2026-05-06T01:20:34.868559Z","end":"2026-05-06T01:20:35.011935Z","steps":["trace[1542824377] 'process raft request' (duration: 39.017879ms)","trace[1542824377] 'compare' (duration: 103.873796ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:20:35.130791Z","caller":"traceutil/trace.go:171","msg":"trace[1408115334] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"113.967684ms","start":"2026-05-06T01:20:35.016801Z","end":"2026-05-06T01:20:35.130769Z","steps":["trace[1408115334] 'process raft request' (duration: 113.81969ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:35.557103Z","caller":"traceutil/trace.go:171","msg":"trace[45216053] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1321; }","duration":"121.889131ms","start":"2026-05-06T01:20:35.435186Z","end":"2026-05-06T01:20:35.557075Z","steps":["trace[45216053] 'read index received' (duration: 121.735248ms)","trace[45216053] 'applied index is now lower than readState.Index' (duration: 152.813µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:20:35.557187Z","caller":"traceutil/trace.go:171","msg":"trace[1573629529] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"274.784551ms","start":"2026-05-06T01:20:35.282365Z","end":"2026-05-06T01:20:35.55715Z","steps":["trace[1573629529] 'process raft request' (duration: 274.539535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:35.557383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.182538ms","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-05-06T01:20:35.557432Z","caller":"traceutil/trace.go:171","msg":"trace[1227930466] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1271; }","duration":"122.30776ms","start":"2026-05-06T01:20:35.435111Z","end":"2026-05-06T01:20:35.557419Z","steps":["trace[1227930466] 'agreement among raft nodes before linearized reading' (duration: 122.124476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:35.557554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.5737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T01:20:35.5576Z","caller":"traceutil/trace.go:171","msg":"trace[1872540520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1271; }","duration":"120.648701ms","start":"2026-05-06T01:20:35.436939Z","end":"2026-05-06T01:20:35.557588Z","steps":["trace[1872540520] 'agreement among raft nodes before linearized reading' (duration: 120.477007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:35.557644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.116112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-06T01:20:35.557726Z","caller":"traceutil/trace.go:171","msg":"trace[203370153] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1271; }","duration":"121.225555ms","start":"2026-05-06T01:20:35.436481Z","end":"2026-05-06T01:20:35.557707Z","steps":["trace[203370153] 'agreement among raft nodes before linearized reading' (duration: 120.98948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:35.557765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.733794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-06T01:20:35.557848Z","caller":"traceutil/trace.go:171","msg":"trace[1956264517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1271; }","duration":"120.849606ms","start":"2026-05-06T01:20:35.436981Z","end":"2026-05-06T01:20:35.557831Z","steps":["trace[1956264517] 'agreement among raft nodes before linearized reading' (duration: 120.58071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:35.557655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.812579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-06T01:20:35.557933Z","caller":"traceutil/trace.go:171","msg":"trace[249536885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1271; }","duration":"122.306661ms","start":"2026-05-06T01:20:35.435603Z","end":"2026-05-06T01:20:35.55791Z","steps":["trace[249536885] 'agreement among raft nodes before linearized reading' (duration: 121.807009ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:35.771138Z","caller":"traceutil/trace.go:171","msg":"trace[84711995] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"207.423415ms","start":"2026-05-06T01:20:35.563686Z","end":"2026-05-06T01:20:35.771109Z","steps":["trace[84711995] 'process raft request' (duration: 114.149578ms)","trace[84711995] 'compare' (duration: 93.158474ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:20:35.867155Z","caller":"traceutil/trace.go:171","msg":"trace[968773530] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"303.013096ms","start":"2026-05-06T01:20:35.564114Z","end":"2026-05-06T01:20:35.867127Z","steps":["trace[968773530] 'process raft request' (duration: 302.828752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:35.867511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:35.564103Z","time spent":"303.295633ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:20:35.867194Z","caller":"traceutil/trace.go:171","msg":"trace[336003956] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"303.387135ms","start":"2026-05-06T01:20:35.563738Z","end":"2026-05-06T01:20:35.867125Z","steps":["trace[336003956] 'process raft request' (duration: 302.983185ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:35.867654Z","caller":"traceutil/trace.go:171","msg":"trace[394355010] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1322; }","duration":"301.338007ms","start":"2026-05-06T01:20:35.566302Z","end":"2026-05-06T01:20:35.86764Z","steps":["trace[394355010] 'read index received' (duration: 111.507006ms)","trace[394355010] 'applied index is now lower than readState.Index' (duration: 189.829971ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:20:35.867688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:35.563687Z","time spent":"303.910287ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:20:35.867796Z","caller":"traceutil/trace.go:171","msg":"trace[2087367925] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"302.197916ms","start":"2026-05-06T01:20:35.565576Z","end":"2026-05-06T01:20:35.867773Z","steps":["trace[2087367925] 'process raft request' (duration: 301.51129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:35.867835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.507972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-05-06T01:20:35.867868Z","caller":"traceutil/trace.go:171","msg":"trace[1510063607] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"302.264779ms","start":"2026-05-06T01:20:35.565594Z","end":"2026-05-06T01:20:35.867859Z","steps":["trace[1510063607] 'process raft request' (duration: 301.713065ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:35.867943Z","caller":"traceutil/trace.go:171","msg":"trace[321253668] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1276; }","duration":"301.639964ms","start":"2026-05-06T01:20:35.566286Z","end":"2026-05-06T01:20:35.867926Z","steps":["trace[321253668] 'agreement among raft nodes before linearized reading' (duration: 301.413158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:35.86799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:35.566277Z","time spent":"301.700786ms","remote":"127.0.0.1:55716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5536,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-06T01:20:35.867996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:35.565572Z","time spent":"302.366192ms","remote":"127.0.0.1:55804","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-05-06T01:20:35.868005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:35.565568Z","time spent":"302.30068ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:20:36.004433Z","caller":"traceutil/trace.go:171","msg":"trace[676416116] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1327; }","duration":"108.967166ms","start":"2026-05-06T01:20:35.895442Z","end":"2026-05-06T01:20:36.004409Z","steps":["trace[676416116] 'read index received' (duration: 108.801352ms)","trace[676416116] 'applied index is now lower than readState.Index' (duration: 164.794µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:20:36.004585Z","caller":"traceutil/trace.go:171","msg":"trace[971973387] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"124.640635ms","start":"2026-05-06T01:20:35.879851Z","end":"2026-05-06T01:20:36.004492Z","steps":["trace[971973387] 'process raft request' (duration: 124.384859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:36.004586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.111499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T01:20:36.00472Z","caller":"traceutil/trace.go:171","msg":"trace[1221767543] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1277; }","duration":"109.288984ms","start":"2026-05-06T01:20:35.895413Z","end":"2026-05-06T01:20:36.004702Z","steps":["trace[1221767543] 'agreement among raft nodes before linearized reading' (duration: 109.090689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:36.288895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.874941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:20:36.289013Z","caller":"traceutil/trace.go:171","msg":"trace[1821811603] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"108.142727ms","start":"2026-05-06T01:20:36.180852Z","end":"2026-05-06T01:20:36.288994Z","steps":["trace[1821811603] 'read index received' (duration: 44.311µs)","trace[1821811603] 'applied index is now lower than readState.Index' (duration: 108.097206ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:20:36.289087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.24129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:20:36.289135Z","caller":"traceutil/trace.go:171","msg":"trace[1209569499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1278; }","duration":"108.324881ms","start":"2026-05-06T01:20:36.180796Z","end":"2026-05-06T01:20:36.289121Z","steps":["trace[1209569499] 'agreement among raft nodes before linearized reading' (duration: 108.248559ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:36.289147Z","caller":"traceutil/trace.go:171","msg":"trace[1899320352] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"275.512088ms","start":"2026-05-06T01:20:36.013611Z","end":"2026-05-06T01:20:36.289123Z","steps":["trace[1899320352] 'process raft request' (duration: 29.31839ms)","trace[1899320352] 'compare' (duration: 245.763668ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:20:36.867717Z","caller":"traceutil/trace.go:171","msg":"trace[322385031] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"356.373504ms","start":"2026-05-06T01:20:36.51132Z","end":"2026-05-06T01:20:36.867693Z","steps":["trace[322385031] 'process raft request' (duration: 339.529207ms)","trace[322385031] 'compare' (duration: 16.55578ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:20:36.867826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:36.511294Z","time spent":"356.480256ms","remote":"127.0.0.1:55804","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-05-06T01:20:37.139041Z","caller":"traceutil/trace.go:171","msg":"trace[433910455] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"251.80474ms","start":"2026-05-06T01:20:36.887197Z","end":"2026-05-06T01:20:37.139001Z","steps":["trace[433910455] 'process raft request' (duration: 251.652396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:37.465689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.559471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:20:37.465858Z","caller":"traceutil/trace.go:171","msg":"trace[1266887352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1280; }","duration":"281.823468ms","start":"2026-05-06T01:20:37.184009Z","end":"2026-05-06T01:20:37.465832Z","steps":["trace[1266887352] 'range keys from in-memory index tree' (duration: 281.458869ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:45.498273Z","caller":"traceutil/trace.go:171","msg":"trace[1646025737] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1400; }","duration":"314.467845ms","start":"2026-05-06T01:20:45.183752Z","end":"2026-05-06T01:20:45.49822Z","steps":["trace[1646025737] 'read index received' (duration: 314.432204ms)","trace[1646025737] 'applied index is now lower than readState.Index' (duration: 34.261µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:20:45.498345Z","caller":"traceutil/trace.go:171","msg":"trace[2113543101] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"359.347552ms","start":"2026-05-06T01:20:45.138962Z","end":"2026-05-06T01:20:45.498309Z","steps":["trace[2113543101] 'process raft request' (duration: 359.115637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:45.498525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:45.138943Z","time spent":"359.477695ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:20:45.498589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.729961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:20:45.498797Z","caller":"traceutil/trace.go:171","msg":"trace[1404184274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1349; }","duration":"315.076139ms","start":"2026-05-06T01:20:45.183692Z","end":"2026-05-06T01:20:45.498768Z","steps":["trace[1404184274] 'agreement among raft nodes before linearized reading' (duration: 314.683019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:45.498952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:45.18367Z","time spent":"315.264264ms","remote":"127.0.0.1:55544","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-05-06T01:20:46.749989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.236232614s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-06T01:20:46.750599Z","caller":"traceutil/trace.go:171","msg":"trace[1070220965] linearizableReadLoop","detail":"{readStateIndex:1402; appliedIndex:1401; }","duration":"1.250164492s","start":"2026-05-06T01:20:45.50037Z","end":"2026-05-06T01:20:46.750535Z","steps":["trace[1070220965] 'read index received' (duration: 13.311994ms)","trace[1070220965] 'applied index is now lower than readState.Index' (duration: 1.236850958s)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:20:46.750695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.250312565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:20:46.750776Z","caller":"traceutil/trace.go:171","msg":"trace[1080548621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1349; }","duration":"1.250436788s","start":"2026-05-06T01:20:45.500328Z","end":"2026-05-06T01:20:46.750765Z","steps":["trace[1080548621] 'agreement among raft nodes before linearized reading' (duration: 1.250322366s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:46.750808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:45.50031Z","time spent":"1.25049039s","remote":"127.0.0.1:55556","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-05-06T01:20:46.753941Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.117330494s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-06T01:20:46.755287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.900432ms","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-05-06T01:20:46.755339Z","caller":"traceutil/trace.go:171","msg":"trace[415261331] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1350; }","duration":"834.973814ms","start":"2026-05-06T01:20:45.920349Z","end":"2026-05-06T01:20:46.755323Z","steps":["trace[415261331] 'agreement among raft nodes before linearized reading' (duration: 834.79618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:46.755366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:45.920337Z","time spent":"835.022675ms","remote":"127.0.0.1:55804","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-05-06T01:20:46.755366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.079827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-06T01:20:46.755408Z","caller":"traceutil/trace.go:171","msg":"trace[319690475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1350; }","duration":"849.135568ms","start":"2026-05-06T01:20:45.906258Z","end":"2026-05-06T01:20:46.755394Z","steps":["trace[319690475] 'agreement among raft nodes before linearized reading' (duration: 849.053646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:46.75545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:45.906248Z","time spent":"849.194589ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T01:20:46.755576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.277597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-06T01:20:46.755609Z","caller":"traceutil/trace.go:171","msg":"trace[395907510] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1350; }","duration":"876.345019ms","start":"2026-05-06T01:20:45.879255Z","end":"2026-05-06T01:20:46.7556Z","steps":["trace[395907510] 'agreement among raft nodes before linearized reading' (duration: 876.266727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:46.755636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:45.879237Z","time spent":"876.39338ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-05-06T01:20:46.755686Z","caller":"traceutil/trace.go:171","msg":"trace[269766121] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"1.119178968s","start":"2026-05-06T01:20:45.636499Z","end":"2026-05-06T01:20:46.755678Z","steps":["trace[269766121] 'process raft request' (duration: 1.118293697s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:46.755868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:45.636477Z","time spent":"1.119345411s","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:20:46.756197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.112272ms","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-05-06T01:20:46.756238Z","caller":"traceutil/trace.go:171","msg":"trace[523630810] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1350; }","duration":"411.199254ms","start":"2026-05-06T01:20:46.345033Z","end":"2026-05-06T01:20:46.756232Z","steps":["trace[523630810] 'agreement among raft nodes before linearized reading' (duration: 410.979999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:46.756356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:46.345009Z","time spent":"411.244495ms","remote":"127.0.0.1:55612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":160,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:20:46.755269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.273195ms","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-05-06T01:20:46.75644Z","caller":"traceutil/trace.go:171","msg":"trace[1168567405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1350; }","duration":"837.496614ms","start":"2026-05-06T01:20:45.918931Z","end":"2026-05-06T01:20:46.756428Z","steps":["trace[1168567405] 'agreement among raft nodes before linearized reading' (duration: 836.216704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:46.756494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:45.918909Z","time spent":"837.572246ms","remote":"127.0.0.1:55804","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-05-06T01:20:46.756679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.693876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-06T01:20:46.756714Z","caller":"traceutil/trace.go:171","msg":"trace[1267649791] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1350; }","duration":"828.753637ms","start":"2026-05-06T01:20:45.927951Z","end":"2026-05-06T01:20:46.756705Z","steps":["trace[1267649791] 'agreement among raft nodes before linearized reading' (duration: 828.665395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:46.756742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:45.927939Z","time spent":"828.796929ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T01:20:46.75685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.145615ms","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-05-06T01:20:46.75688Z","caller":"traceutil/trace.go:171","msg":"trace[399005803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1350; }","duration":"831.202996ms","start":"2026-05-06T01:20:45.925669Z","end":"2026-05-06T01:20:46.756872Z","steps":["trace[399005803] 'agreement among raft nodes before linearized reading' (duration: 831.132975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:46.75691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:20:45.925651Z","time spent":"831.253677ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T01:21:35.135722Z","caller":"traceutil/trace.go:171","msg":"trace[778776921] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"115.411329ms","start":"2026-05-06T01:21:35.020273Z","end":"2026-05-06T01:21:35.135684Z","steps":["trace[778776921] 'process raft request' (duration: 115.179044ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:21:39.121204Z","caller":"traceutil/trace.go:171","msg":"trace[711657227] linearizableReadLoop","detail":"{readStateIndex:1654; appliedIndex:1653; }","duration":"115.8737ms","start":"2026-05-06T01:21:39.005304Z","end":"2026-05-06T01:21:39.121178Z","steps":["trace[711657227] 'read index received' (duration: 115.693396ms)","trace[711657227] 'applied index is now lower than readState.Index' (duration: 179.294µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:21:39.121282Z","caller":"traceutil/trace.go:171","msg":"trace[905403033] transaction","detail":"{read_only:false; response_revision:1591; number_of_response:1; }","duration":"123.782516ms","start":"2026-05-06T01:21:38.997476Z","end":"2026-05-06T01:21:39.121258Z","steps":["trace[905403033] 'process raft request' (duration: 123.54169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:21:39.121433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.114776ms","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-05-06T01:21:39.121485Z","caller":"traceutil/trace.go:171","msg":"trace[942562911] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1591; }","duration":"116.231509ms","start":"2026-05-06T01:21:39.00524Z","end":"2026-05-06T01:21:39.121471Z","steps":["trace[942562911] 'agreement among raft nodes before linearized reading' (duration: 116.044865ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:21:39.345806Z","caller":"traceutil/trace.go:171","msg":"trace[1710406170] transaction","detail":"{read_only:false; response_revision:1597; number_of_response:1; }","duration":"157.982889ms","start":"2026-05-06T01:21:39.187771Z","end":"2026-05-06T01:21:39.345754Z","steps":["trace[1710406170] 'process raft request' (duration: 125.270181ms)","trace[1710406170] 'compare' (duration: 32.294188ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:21:39.345909Z","caller":"traceutil/trace.go:171","msg":"trace[432951716] linearizableReadLoop","detail":"{readStateIndex:1661; appliedIndex:1659; }","duration":"132.215294ms","start":"2026-05-06T01:21:39.21367Z","end":"2026-05-06T01:21:39.345885Z","steps":["trace[432951716] 'read index received' (duration: 99.369563ms)","trace[432951716] 'applied index is now lower than readState.Index' (duration: 32.844461ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:21:39.346059Z","caller":"traceutil/trace.go:171","msg":"trace[2004711706] transaction","detail":"{read_only:false; response_revision:1598; number_of_response:1; }","duration":"158.194334ms","start":"2026-05-06T01:21:39.187847Z","end":"2026-05-06T01:21:39.346041Z","steps":["trace[2004711706] 'process raft request' (duration: 157.812485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:21:39.346095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.389338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:21:39.346159Z","caller":"traceutil/trace.go:171","msg":"trace[1648909639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1598; }","duration":"132.510901ms","start":"2026-05-06T01:21:39.213612Z","end":"2026-05-06T01:21:39.346123Z","steps":["trace[1648909639] 'agreement among raft nodes before linearized reading' (duration: 132.402608ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:22:03.666754Z","caller":"traceutil/trace.go:171","msg":"trace[1263950672] transaction","detail":"{read_only:false; response_revision:1705; number_of_response:1; }","duration":"140.665766ms","start":"2026-05-06T01:22:03.526059Z","end":"2026-05-06T01:22:03.666724Z","steps":["trace[1263950672] 'process raft request' (duration: 133.27042ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:22:03.66679Z","caller":"traceutil/trace.go:171","msg":"trace[2056959675] transaction","detail":"{read_only:false; response_revision:1706; number_of_response:1; }","duration":"139.685354ms","start":"2026-05-06T01:22:03.527053Z","end":"2026-05-06T01:22:03.666738Z","steps":["trace[2056959675] 'process raft request' (duration: 139.548951ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:22:03.666838Z","caller":"traceutil/trace.go:171","msg":"trace[411543765] transaction","detail":"{read_only:false; response_revision:1707; number_of_response:1; }","duration":"138.21402ms","start":"2026-05-06T01:22:03.528614Z","end":"2026-05-06T01:22:03.666828Z","steps":["trace[411543765] 'process raft request' (duration: 138.032546ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:22:03.66677Z","caller":"traceutil/trace.go:171","msg":"trace[870159761] transaction","detail":"{read_only:false; response_revision:1708; number_of_response:1; }","duration":"137.925444ms","start":"2026-05-06T01:22:03.528817Z","end":"2026-05-06T01:22:03.666743Z","steps":["trace[870159761] 'process raft request' (duration: 137.859373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:22:05.045703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.657432ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-06T01:22:05.070998Z","caller":"traceutil/trace.go:171","msg":"trace[1856621405] linearizableReadLoop","detail":"{readStateIndex:1779; appliedIndex:1778; }","duration":"171.770124ms","start":"2026-05-06T01:22:04.89917Z","end":"2026-05-06T01:22:05.07094Z","steps":["trace[1856621405] 'read index received' (duration: 171.521008ms)","trace[1856621405] 'applied index is now lower than readState.Index' (duration: 248.106µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:22:05.07113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.927487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:22:05.071171Z","caller":"traceutil/trace.go:171","msg":"trace[1992836176] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1710; }","duration":"172.020729ms","start":"2026-05-06T01:22:04.899138Z","end":"2026-05-06T01:22:05.071158Z","steps":["trace[1992836176] 'agreement among raft nodes before linearized reading' (duration: 171.928137ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:22:05.071196Z","caller":"traceutil/trace.go:171","msg":"trace[904243065] transaction","detail":"{read_only:false; response_revision:1710; number_of_response:1; }","duration":"217.710925ms","start":"2026-05-06T01:22:04.85341Z","end":"2026-05-06T01:22:05.071121Z","steps":["trace[904243065] 'process raft request' (duration: 217.380117ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:22:15.614692Z","caller":"traceutil/trace.go:171","msg":"trace[932624442] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"117.596578ms","start":"2026-05-06T01:22:15.497062Z","end":"2026-05-06T01:22:15.614659Z","steps":["trace[932624442] 'process raft request' (duration: 66.210715ms)","trace[932624442] 'compare' (duration: 51.22682ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:22:15.802954Z","caller":"traceutil/trace.go:171","msg":"trace[1053655213] linearizableReadLoop","detail":"{readStateIndex:1829; appliedIndex:1828; }","duration":"108.453954ms","start":"2026-05-06T01:22:15.694434Z","end":"2026-05-06T01:22:15.802888Z","steps":["trace[1053655213] 'read index received' (duration: 24.123602ms)","trace[1053655213] 'applied index is now lower than readState.Index' (duration: 84.329042ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:22:15.803303Z","caller":"traceutil/trace.go:171","msg":"trace[1855961373] transaction","detail":"{read_only:false; response_revision:1758; number_of_response:1; }","duration":"140.629015ms","start":"2026-05-06T01:22:15.662652Z","end":"2026-05-06T01:22:15.803281Z","steps":["trace[1855961373] 'process raft request' (duration: 55.976236ms)","trace[1855961373] 'compare' (duration: 84.091926ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:22:15.803549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.085188ms","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-05-06T01:22:15.803687Z","caller":"traceutil/trace.go:171","msg":"trace[1501783394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1758; }","duration":"109.279012ms","start":"2026-05-06T01:22:15.694384Z","end":"2026-05-06T01:22:15.803663Z","steps":["trace[1501783394] 'agreement among raft nodes before linearized reading' (duration: 108.988266ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:23:34.561479Z","caller":"traceutil/trace.go:171","msg":"trace[1199131101] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"161.08094ms","start":"2026-05-06T01:23:34.400371Z","end":"2026-05-06T01:23:34.561452Z","steps":["trace[1199131101] 'process raft request' (duration: 120.090723ms)","trace[1199131101] 'compare' (duration: 40.800963ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:23:34.561681Z","caller":"traceutil/trace.go:171","msg":"trace[1789546779] linearizableReadLoop","detail":"{readStateIndex:2206; appliedIndex:2204; }","duration":"137.339847ms","start":"2026-05-06T01:23:34.424314Z","end":"2026-05-06T01:23:34.561654Z","steps":["trace[1789546779] 'read index received' (duration: 96.103434ms)","trace[1789546779] 'applied index is now lower than readState.Index' (duration: 41.235553ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:23:34.561785Z","caller":"traceutil/trace.go:171","msg":"trace[202679600] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"160.098337ms","start":"2026-05-06T01:23:34.401675Z","end":"2026-05-06T01:23:34.561774Z","steps":["trace[202679600] 'process raft request' (duration: 159.871882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:23:34.562006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.685195ms","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-05-06T01:23:34.562097Z","caller":"traceutil/trace.go:171","msg":"trace[1537755494] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2120; }","duration":"137.819148ms","start":"2026-05-06T01:23:34.424257Z","end":"2026-05-06T01:23:34.562076Z","steps":["trace[1537755494] 'agreement among raft nodes before linearized reading' (duration: 137.652224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:23:35.406965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.366278ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-06T01:23:35.407074Z","caller":"traceutil/trace.go:171","msg":"trace[1029345093] linearizableReadLoop","detail":"{readStateIndex:2211; appliedIndex:2210; }","duration":"362.786649ms","start":"2026-05-06T01:23:35.044267Z","end":"2026-05-06T01:23:35.407054Z","steps":["trace[1029345093] 'read index received' (duration: 93.252537ms)","trace[1029345093] 'applied index is now lower than readState.Index' (duration: 269.532712ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:23:35.40728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.000964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T01:23:35.407371Z","caller":"traceutil/trace.go:171","msg":"trace[637450126] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2124; }","duration":"363.134147ms","start":"2026-05-06T01:23:35.04422Z","end":"2026-05-06T01:23:35.407354Z","steps":["trace[637450126] 'agreement among raft nodes before linearized reading' (duration: 362.986433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:23:35.407535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:23:35.0442Z","time spent":"363.324841ms","remote":"127.0.0.1:55850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:23:35.40728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.274349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:23:35.407719Z","caller":"traceutil/trace.go:171","msg":"trace[141336393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2124; }","duration":"225.716539ms","start":"2026-05-06T01:23:35.181982Z","end":"2026-05-06T01:23:35.407698Z","steps":["trace[141336393] 'agreement among raft nodes before linearized reading' (duration: 225.235998ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:23:52.535918Z","caller":"traceutil/trace.go:171","msg":"trace[45251873] linearizableReadLoop","detail":"{readStateIndex:2317; appliedIndex:2316; }","duration":"123.117055ms","start":"2026-05-06T01:23:52.412749Z","end":"2026-05-06T01:23:52.535866Z","steps":["trace[45251873] 'read index received' (duration: 122.89596ms)","trace[45251873] 'applied index is now lower than readState.Index' (duration: 220.165µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:23:52.536087Z","caller":"traceutil/trace.go:171","msg":"trace[546632241] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"204.340112ms","start":"2026-05-06T01:23:52.331732Z","end":"2026-05-06T01:23:52.536073Z","steps":["trace[546632241] 'process raft request' (duration: 204.002814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:23:52.536889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.126319ms","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-05-06T01:23:52.536943Z","caller":"traceutil/trace.go:171","msg":"trace[910221269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2226; }","duration":"124.22384ms","start":"2026-05-06T01:23:52.412706Z","end":"2026-05-06T01:23:52.536929Z","steps":["trace[910221269] 'agreement among raft nodes before linearized reading' (duration: 123.283669ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:24:01.88739Z","caller":"traceutil/trace.go:171","msg":"trace[12839740] transaction","detail":"{read_only:false; response_revision:2276; number_of_response:1; }","duration":"245.140921ms","start":"2026-05-06T01:24:01.642219Z","end":"2026-05-06T01:24:01.88736Z","steps":["trace[12839740] 'process raft request' (duration: 244.981297ms)"],"step_count":1}