{"level":"warn","ts":"2026-05-07T03:25:38.862182Z","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-07T03:25:38.862268Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-07T03:25:38.862327Z","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-07T03:25:38.862337Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-05-07T03:25:38.862355Z","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-07T03:25:38.862975Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-05-07T03:25:38.863044Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-07T03:25:38.86713Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.891284ms"} {"level":"info","ts":"2026-05-07T03:25:38.87326Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-05-07T03:25:38.873352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T03:25:38.873396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-05-07T03:25:38.87342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T03:25:38.87344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-05-07T03:25:38.873476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-05-07T03:25:38.881894Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T03:25:38.883964Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T03:25:38.887709Z","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-07T03:25:38.896244Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T03:25:38.899281Z","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-07T03:25:38.899382Z","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-07T03:25:38.899392Z","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-07T03:25:38.899291Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T03:25:38.90159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-05-07T03:25:38.903059Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-05-07T03:25:38.904029Z","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-07T03:25:38.90412Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-05-07T03:25:38.904172Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-05-07T03:25:38.904264Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T03:25:38.904308Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T03:25:39.674724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T03:25:39.674803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T03:25:39.674838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-05-07T03:25:39.674867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-05-07T03:25:39.674878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-05-07T03:25:39.674893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-05-07T03:25:39.674906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-05-07T03:25:39.677738Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T03:25:39.67779Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T03:25:39.677912Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:25:39.678073Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T03:25:39.678386Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T03:25:39.678424Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T03:25:39.680872Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-05-07T03:25:39.681744Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T03:25:39.695639Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:25:39.695766Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:25:39.6958Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-07T03:25:42.98047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.018516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:25:42.980716Z","caller":"traceutil/trace.go:171","msg":"trace[1782441584] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"223.173595ms","start":"2026-05-07T03:25:42.757523Z","end":"2026-05-07T03:25:42.980697Z","steps":["trace[1782441584] 'process raft request' (duration: 82.408731ms)","trace[1782441584] 'compare' (duration: 139.885183ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:25:43.151879Z","caller":"traceutil/trace.go:171","msg":"trace[895134427] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"219.395865ms","start":"2026-05-07T03:25:42.932451Z","end":"2026-05-07T03:25:43.151847Z","steps":["trace[895134427] 'process raft request' (duration: 176.884683ms)","trace[895134427] 'compare' (duration: 42.338648ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:25:43.362942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.721627ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:25:43.363191Z","caller":"traceutil/trace.go:171","msg":"trace[1863528536] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"130.695532ms","start":"2026-05-07T03:25:43.232429Z","end":"2026-05-07T03:25:43.363125Z","steps":["trace[1863528536] 'process raft request' (duration: 20.674807ms)","trace[1863528536] 'compare' (duration: 109.588024ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:25:43.66554Z","caller":"traceutil/trace.go:171","msg":"trace[797803897] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"298.427724ms","start":"2026-05-07T03:25:43.36708Z","end":"2026-05-07T03:25:43.665508Z","steps":["trace[797803897] 'process raft request' (duration: 248.991006ms)","trace[797803897] 'compare' (duration: 49.282694ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:25:54.34132Z","caller":"traceutil/trace.go:171","msg":"trace[926941259] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"258.142875ms","start":"2026-05-07T03:25:54.083143Z","end":"2026-05-07T03:25:54.341286Z","steps":["trace[926941259] 'process raft request' (duration: 257.92531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:25:54.341499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.154346ms","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-07T03:25:54.341595Z","caller":"traceutil/trace.go:171","msg":"trace[14540252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"211.302159ms","start":"2026-05-07T03:25:54.130273Z","end":"2026-05-07T03:25:54.341575Z","steps":["trace[14540252] 'agreement among raft nodes before linearized reading' (duration: 211.101685ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:25:54.341682Z","caller":"traceutil/trace.go:171","msg":"trace[1181662021] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"210.912281ms","start":"2026-05-07T03:25:54.1303Z","end":"2026-05-07T03:25:54.341213Z","steps":["trace[1181662021] 'read index received' (duration: 210.732136ms)","trace[1181662021] 'applied index is now lower than readState.Index' (duration: 179.165µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:26:01.176206Z","caller":"traceutil/trace.go:171","msg":"trace[89185628] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"102.974445ms","start":"2026-05-07T03:26:01.073205Z","end":"2026-05-07T03:26:01.17618Z","steps":["trace[89185628] 'process raft request' (duration: 102.838952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:02.884425Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880765309205740,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:26:03.385283Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880765309205740,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:26:03.802672Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.419574186s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T03:26:03.803052Z","caller":"traceutil/trace.go:171","msg":"trace[2145440880] linearizableReadLoop","detail":"{readStateIndex:472; appliedIndex:471; }","duration":"1.419535915s","start":"2026-05-07T03:26:02.383492Z","end":"2026-05-07T03:26:03.803027Z","steps":["trace[2145440880] 'read index received' (duration: 1.419355741s)","trace[2145440880] 'applied index is now lower than readState.Index' (duration: 179.094µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:26:03.803148Z","caller":"traceutil/trace.go:171","msg":"trace[399696423] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"1.42012329s","start":"2026-05-07T03:26:02.382984Z","end":"2026-05-07T03:26:03.803108Z","steps":["trace[399696423] 'process raft request' (duration: 1.419919225s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:03.803218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.419699769s","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-07T03:26:03.803275Z","caller":"traceutil/trace.go:171","msg":"trace[717108646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:457; }","duration":"1.419785061s","start":"2026-05-07T03:26:02.383475Z","end":"2026-05-07T03:26:03.80326Z","steps":["trace[717108646] 'agreement among raft nodes before linearized reading' (duration: 1.419661659s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:03.803323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:02.383468Z","time spent":"1.419836973s","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T03:26:03.804058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149604276s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T03:26:03.804119Z","caller":"traceutil/trace.go:171","msg":"trace[1487302023] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:457; }","duration":"1.149710349s","start":"2026-05-07T03:26:02.654396Z","end":"2026-05-07T03:26:03.804107Z","steps":["trace[1487302023] 'agreement among raft nodes before linearized reading' (duration: 1.149596066s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:03.80415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:02.654382Z","time spent":"1.14976098s","remote":"127.0.0.1:34400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"warn","ts":"2026-05-07T03:26:03.804153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.122109ms","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-07T03:26:03.804214Z","caller":"traceutil/trace.go:171","msg":"trace[1020687087] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:457; }","duration":"623.209972ms","start":"2026-05-07T03:26:03.180989Z","end":"2026-05-07T03:26:03.804199Z","steps":["trace[1020687087] 'agreement among raft nodes before linearized reading' (duration: 623.061448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:03.804209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.1939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:26:03.804282Z","caller":"traceutil/trace.go:171","msg":"trace[1946827403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:457; }","duration":"952.323184ms","start":"2026-05-07T03:26:02.851941Z","end":"2026-05-07T03:26:03.804264Z","steps":["trace[1946827403] 'agreement among raft nodes before linearized reading' (duration: 952.146099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:03.804342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:02.851921Z","time spent":"952.413055ms","remote":"127.0.0.1:34652","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-07T03:26:03.80426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:03.180934Z","time spent":"623.318694ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T03:26:03.804681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:02.382958Z","time spent":"1.420265803s","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:26:06.573534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.835013ms","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-07T03:26:06.573609Z","caller":"traceutil/trace.go:171","msg":"trace[1249960677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:462; }","duration":"741.929676ms","start":"2026-05-07T03:26:05.831663Z","end":"2026-05-07T03:26:06.573593Z","steps":["trace[1249960677] 'range keys from in-memory index tree' (duration: 741.757142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:06.573637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:05.831657Z","time spent":"741.972897ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T03:26:06.573941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.649768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:26:06.573972Z","caller":"traceutil/trace.go:171","msg":"trace[1407780029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"513.71673ms","start":"2026-05-07T03:26:06.060245Z","end":"2026-05-07T03:26:06.573962Z","steps":["trace[1407780029] 'range keys from in-memory index tree' (duration: 513.544785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:06.573989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:06.060224Z","time spent":"513.761121ms","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:26:06.573965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.021992ms","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-07T03:26:06.574018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.374789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-07T03:26:06.574013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.381917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:26:06.574039Z","caller":"traceutil/trace.go:171","msg":"trace[929278262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:462; }","duration":"743.103934ms","start":"2026-05-07T03:26:05.830915Z","end":"2026-05-07T03:26:06.574019Z","steps":["trace[929278262] 'range keys from in-memory index tree' (duration: 742.93715ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:26:06.574057Z","caller":"traceutil/trace.go:171","msg":"trace[1576080465] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:462; }","duration":"765.452501ms","start":"2026-05-07T03:26:05.808593Z","end":"2026-05-07T03:26:06.574046Z","steps":["trace[1576080465] 'range keys from in-memory index tree' (duration: 765.298518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:06.574087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:05.808573Z","time spent":"765.507203ms","remote":"127.0.0.1:34652","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-07T03:26:06.574087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:05.83088Z","time spent":"743.198057ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T03:26:06.574091Z","caller":"traceutil/trace.go:171","msg":"trace[1749680360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:462; }","duration":"742.542391ms","start":"2026-05-07T03:26:05.831528Z","end":"2026-05-07T03:26:06.574071Z","steps":["trace[1749680360] 'range keys from in-memory index tree' (duration: 742.271224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:06.574132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:05.831507Z","time spent":"742.615743ms","remote":"127.0.0.1:34652","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-07T03:26:06.714639Z","caller":"traceutil/trace.go:171","msg":"trace[1181584057] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"136.517332ms","start":"2026-05-07T03:26:06.578097Z","end":"2026-05-07T03:26:06.714614Z","steps":["trace[1181584057] 'process raft request' (duration: 136.469511ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:26:06.714835Z","caller":"traceutil/trace.go:171","msg":"trace[647493556] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"136.87018ms","start":"2026-05-07T03:26:06.577952Z","end":"2026-05-07T03:26:06.714822Z","steps":["trace[647493556] 'process raft request' (duration: 136.548272ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:26:06.714664Z","caller":"traceutil/trace.go:171","msg":"trace[511097000] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"136.692106ms","start":"2026-05-07T03:26:06.577952Z","end":"2026-05-07T03:26:06.714644Z","steps":["trace[511097000] 'process raft request' (duration: 110.594048ms)","trace[511097000] 'compare' (duration: 25.858982ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:26:08.705409Z","caller":"traceutil/trace.go:171","msg":"trace[1736273510] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"128.871358ms","start":"2026-05-07T03:26:08.576514Z","end":"2026-05-07T03:26:08.705386Z","steps":["trace[1736273510] 'process raft request' (duration: 122.214608ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:26:09.409195Z","caller":"traceutil/trace.go:171","msg":"trace[1345044418] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"129.649246ms","start":"2026-05-07T03:26:09.279523Z","end":"2026-05-07T03:26:09.409172Z","steps":["trace[1345044418] 'process raft request' (duration: 129.453621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:11.514669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:11.214524Z","time spent":"300.135275ms","remote":"127.0.0.1:46196","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-07T03:26:11.515082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.219509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:26:11.515352Z","caller":"traceutil/trace.go:171","msg":"trace[100874022] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.059314265s","start":"2026-05-07T03:26:10.45586Z","end":"2026-05-07T03:26:11.515174Z","steps":["trace[100874022] 'process raft request' (duration: 344.800559ms)","trace[100874022] 'compare' (duration: 714.104536ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:26:11.515817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:10.455844Z","time spent":"1.059578071s","remote":"127.0.0.1:34428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:26:11.529943Z","caller":"traceutil/trace.go:171","msg":"trace[191011598] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:555; }","duration":"812.82905ms","start":"2026-05-07T03:26:10.717089Z","end":"2026-05-07T03:26:11.529918Z","steps":["trace[191011598] 'read index received' (duration: 83.652881ms)","trace[191011598] 'applied index is now lower than readState.Index' (duration: 729.174969ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:26:11.530018Z","caller":"traceutil/trace.go:171","msg":"trace[338729334] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.013772901s","start":"2026-05-07T03:26:10.516225Z","end":"2026-05-07T03:26:11.529998Z","steps":["trace[338729334] 'process raft request' (duration: 1.013581056s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:26:11.530039Z","caller":"traceutil/trace.go:171","msg":"trace[1077280647] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.060711359s","start":"2026-05-07T03:26:10.469292Z","end":"2026-05-07T03:26:11.530003Z","steps":["trace[1077280647] 'process raft request' (duration: 1.060307029s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:11.530256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.143327ms","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-07T03:26:11.530325Z","caller":"traceutil/trace.go:171","msg":"trace[2000434021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"813.26452ms","start":"2026-05-07T03:26:10.717045Z","end":"2026-05-07T03:26:11.530309Z","steps":["trace[2000434021] 'agreement among raft nodes before linearized reading' (duration: 813.067076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:11.530363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:10.717025Z","time spent":"813.331012ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T03:26:11.530373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:10.469277Z","time spent":"1.060988195s","remote":"127.0.0.1:34428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:26:11.530258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:10.516199Z","time spent":"1.013860952s","remote":"127.0.0.1:34428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:26:12.432117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.932109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:26:12.433291Z","caller":"traceutil/trace.go:171","msg":"trace[811771940] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"742.445718ms","start":"2026-05-07T03:26:11.690823Z","end":"2026-05-07T03:26:12.433269Z","steps":["trace[811771940] 'process raft request' (duration: 742.402477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:12.43355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:11.690799Z","time spent":"742.567271ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:26:12.433671Z","caller":"traceutil/trace.go:171","msg":"trace[1749147276] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"898.382517ms","start":"2026-05-07T03:26:11.535255Z","end":"2026-05-07T03:26:12.433638Z","steps":["trace[1749147276] 'process raft request' (duration: 897.574397ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:26:12.433722Z","caller":"traceutil/trace.go:171","msg":"trace[231532248] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:558; }","duration":"903.656333ms","start":"2026-05-07T03:26:11.530032Z","end":"2026-05-07T03:26:12.433689Z","steps":["trace[231532248] 'read index received' (duration: 226.013643ms)","trace[231532248] 'applied index is now lower than readState.Index' (duration: 677.63987ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:26:12.433774Z","caller":"traceutil/trace.go:171","msg":"trace[1341497672] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"845.713491ms","start":"2026-05-07T03:26:11.588049Z","end":"2026-05-07T03:26:12.433763Z","steps":["trace[1341497672] 'process raft request' (duration: 845.000164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:12.433829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:11.588023Z","time spent":"845.779952ms","remote":"127.0.0.1:34428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:26:12.433834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:11.53524Z","time spent":"898.506449ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:26:12.433842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37647983s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:26:12.433922Z","caller":"traceutil/trace.go:171","msg":"trace[1834984546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"1.376593333s","start":"2026-05-07T03:26:11.057307Z","end":"2026-05-07T03:26:12.4339Z","steps":["trace[1834984546] 'agreement among raft nodes before linearized reading' (duration: 1.376410259s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:26:12.433972Z","caller":"traceutil/trace.go:171","msg":"trace[1970421088] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"843.732293ms","start":"2026-05-07T03:26:11.590229Z","end":"2026-05-07T03:26:12.433961Z","steps":["trace[1970421088] 'process raft request' (duration: 842.885202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:12.433869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.707151059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-07T03:26:12.434001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.699476994s","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-07T03:26:12.434026Z","caller":"traceutil/trace.go:171","msg":"trace[1418909491] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:544; }","duration":"1.707356914s","start":"2026-05-07T03:26:10.726654Z","end":"2026-05-07T03:26:12.434011Z","steps":["trace[1418909491] 'agreement among raft nodes before linearized reading' (duration: 1.707144849s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:12.434103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:10.726635Z","time spent":"1.707455506s","remote":"127.0.0.1:34652","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-07T03:26:12.434166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.7005624s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:26:12.434217Z","caller":"traceutil/trace.go:171","msg":"trace[447587827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:544; }","duration":"1.700632893s","start":"2026-05-07T03:26:10.733572Z","end":"2026-05-07T03:26:12.434205Z","steps":["trace[447587827] 'agreement among raft nodes before linearized reading' (duration: 1.700483489s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:12.433975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:11.057288Z","time spent":"1.376676435s","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:26:12.434268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:10.733562Z","time spent":"1.700695674s","remote":"127.0.0.1:34652","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-07T03:26:12.434274Z","caller":"traceutil/trace.go:171","msg":"trace[1388699830] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"907.406884ms","start":"2026-05-07T03:26:11.526855Z","end":"2026-05-07T03:26:12.434262Z","steps":["trace[1388699830] 'process raft request' (duration: 229.21113ms)","trace[1388699830] 'compare' (duration: 675.773716ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:26:12.434037Z","caller":"traceutil/trace.go:171","msg":"trace[2053265172] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:544; }","duration":"1.699528366s","start":"2026-05-07T03:26:10.734495Z","end":"2026-05-07T03:26:12.434024Z","steps":["trace[2053265172] 'agreement among raft nodes before linearized reading' (duration: 1.699397073s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:12.434393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:10.734483Z","time spent":"1.699896355s","remote":"127.0.0.1:34652","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-07T03:26:12.434036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:11.590202Z","time spent":"843.803175ms","remote":"127.0.0.1:34428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:26:12.434335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:11.526832Z","time spent":"907.474385ms","remote":"127.0.0.1:34428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:26:12.434582Z","caller":"traceutil/trace.go:171","msg":"trace[1871380541] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"891.179803ms","start":"2026-05-07T03:26:11.543387Z","end":"2026-05-07T03:26:12.434566Z","steps":["trace[1871380541] 'process raft request' (duration: 889.573195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:12.434706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:11.543373Z","time spent":"891.273836ms","remote":"127.0.0.1:34428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:26:12.836175Z","caller":"traceutil/trace.go:171","msg":"trace[588397620] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"153.645803ms","start":"2026-05-07T03:26:12.682506Z","end":"2026-05-07T03:26:12.836152Z","steps":["trace[588397620] 'process raft request' (duration: 140.60611ms)","trace[588397620] 'compare' (duration: 12.680645ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:26:12.836666Z","caller":"traceutil/trace.go:171","msg":"trace[433893121] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"153.188463ms","start":"2026-05-07T03:26:12.683458Z","end":"2026-05-07T03:26:12.836646Z","steps":["trace[433893121] 'process raft request' (duration: 152.791804ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:26:13.614154Z","caller":"traceutil/trace.go:171","msg":"trace[471232831] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"207.248562ms","start":"2026-05-07T03:26:13.406878Z","end":"2026-05-07T03:26:13.614126Z","steps":["trace[471232831] 'process raft request' (duration: 207.097728ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:26:13.626773Z","caller":"traceutil/trace.go:171","msg":"trace[782948400] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"196.539255ms","start":"2026-05-07T03:26:13.4302Z","end":"2026-05-07T03:26:13.626739Z","steps":["trace[782948400] 'process raft request' (duration: 196.386031ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:26:19.658404Z","caller":"traceutil/trace.go:171","msg":"trace[1493821901] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"270.37874ms","start":"2026-05-07T03:26:19.388003Z","end":"2026-05-07T03:26:19.658382Z","steps":["trace[1493821901] 'read index received' (duration: 270.230937ms)","trace[1493821901] 'applied index is now lower than readState.Index' (duration: 146.553µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:26:19.658509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.492644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:26:19.658548Z","caller":"traceutil/trace.go:171","msg":"trace[1063634703] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:626; }","duration":"270.569505ms","start":"2026-05-07T03:26:19.38797Z","end":"2026-05-07T03:26:19.658539Z","steps":["trace[1063634703] 'agreement among raft nodes before linearized reading' (duration: 270.487363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:19.658747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.892372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:26:19.658818Z","caller":"traceutil/trace.go:171","msg":"trace[651748237] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:626; }","duration":"181.000465ms","start":"2026-05-07T03:26:19.477798Z","end":"2026-05-07T03:26:19.658798Z","steps":["trace[651748237] 'agreement among raft nodes before linearized reading' (duration: 180.865342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:19.658875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.263709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:26:19.658896Z","caller":"traceutil/trace.go:171","msg":"trace[1699079591] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:626; }","duration":"156.30259ms","start":"2026-05-07T03:26:19.502587Z","end":"2026-05-07T03:26:19.658889Z","steps":["trace[1699079591] 'agreement among raft nodes before linearized reading' (duration: 156.2711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:19.658975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.243953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:26:19.658989Z","caller":"traceutil/trace.go:171","msg":"trace[1971628197] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:626; }","duration":"141.269915ms","start":"2026-05-07T03:26:19.517714Z","end":"2026-05-07T03:26:19.658984Z","steps":["trace[1971628197] 'agreement among raft nodes before linearized reading' (duration: 141.248704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:19.659064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.362726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:26:19.65906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.959836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:26:19.659079Z","caller":"traceutil/trace.go:171","msg":"trace[1345944129] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:626; }","duration":"141.378197ms","start":"2026-05-07T03:26:19.517695Z","end":"2026-05-07T03:26:19.659073Z","steps":["trace[1345944129] 'agreement among raft nodes before linearized reading' (duration: 141.353776ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:26:19.659094Z","caller":"traceutil/trace.go:171","msg":"trace[1025246520] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:626; }","duration":"157.011167ms","start":"2026-05-07T03:26:19.502073Z","end":"2026-05-07T03:26:19.659084Z","steps":["trace[1025246520] 'agreement among raft nodes before linearized reading' (duration: 156.959066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:19.659162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.564183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:26:19.659202Z","caller":"traceutil/trace.go:171","msg":"trace[643698426] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:626; }","duration":"106.647864ms","start":"2026-05-07T03:26:19.552542Z","end":"2026-05-07T03:26:19.65919Z","steps":["trace[643698426] 'agreement among raft nodes before linearized reading' (duration: 106.593593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:19.659233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.17244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:26:19.659262Z","caller":"traceutil/trace.go:171","msg":"trace[1544099135] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:626; }","duration":"157.243612ms","start":"2026-05-07T03:26:19.50201Z","end":"2026-05-07T03:26:19.659253Z","steps":["trace[1544099135] 'agreement among raft nodes before linearized reading' (duration: 157.200651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:19.659302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.741397ms","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-07T03:26:19.659323Z","caller":"traceutil/trace.go:171","msg":"trace[589632059] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:626; }","duration":"106.783477ms","start":"2026-05-07T03:26:19.552534Z","end":"2026-05-07T03:26:19.659317Z","steps":["trace[589632059] 'agreement among raft nodes before linearized reading' (duration: 106.752067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:19.658752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.429314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:26:19.659375Z","caller":"traceutil/trace.go:171","msg":"trace[582841422] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:626; }","duration":"141.07646ms","start":"2026-05-07T03:26:19.518287Z","end":"2026-05-07T03:26:19.659363Z","steps":["trace[582841422] 'agreement among raft nodes before linearized reading' (duration: 140.393333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:19.659642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.100781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:26:19.659657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.179692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:26:19.659667Z","caller":"traceutil/trace.go:171","msg":"trace[1957731508] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:626; }","duration":"182.170763ms","start":"2026-05-07T03:26:19.47749Z","end":"2026-05-07T03:26:19.659661Z","steps":["trace[1957731508] 'agreement among raft nodes before linearized reading' (duration: 182.126722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:19.659673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.645953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:26:19.659693Z","caller":"traceutil/trace.go:171","msg":"trace[1392435063] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:626; }","duration":"239.684174ms","start":"2026-05-07T03:26:19.420003Z","end":"2026-05-07T03:26:19.659687Z","steps":["trace[1392435063] 'agreement among raft nodes before linearized reading' (duration: 239.616913ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:26:19.659689Z","caller":"traceutil/trace.go:171","msg":"trace[1844254607] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:626; }","duration":"182.231484ms","start":"2026-05-07T03:26:19.477448Z","end":"2026-05-07T03:26:19.65968Z","steps":["trace[1844254607] 'agreement among raft nodes before linearized reading' (duration: 182.178083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:19.659657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.947487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:26:19.659749Z","caller":"traceutil/trace.go:171","msg":"trace[1739391703] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:626; }","duration":"239.064169ms","start":"2026-05-07T03:26:19.420657Z","end":"2026-05-07T03:26:19.659721Z","steps":["trace[1739391703] 'agreement among raft nodes before linearized reading' (duration: 238.932806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:19.659809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.931644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:26:19.659824Z","caller":"traceutil/trace.go:171","msg":"trace[1253434173] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:626; }","duration":"270.957604ms","start":"2026-05-07T03:26:19.388862Z","end":"2026-05-07T03:26:19.65982Z","steps":["trace[1253434173] 'agreement among raft nodes before linearized reading' (duration: 270.935304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:19.659775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.687764ms","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-07T03:26:19.659848Z","caller":"traceutil/trace.go:171","msg":"trace[1484099629] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:626; }","duration":"239.755386ms","start":"2026-05-07T03:26:19.420082Z","end":"2026-05-07T03:26:19.659838Z","steps":["trace[1484099629] 'agreement among raft nodes before linearized reading' (duration: 239.679234ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:26:19.658424Z","caller":"traceutil/trace.go:171","msg":"trace[134815153] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"319.015072ms","start":"2026-05-07T03:26:19.33939Z","end":"2026-05-07T03:26:19.658406Z","steps":["trace[134815153] 'process raft request' (duration: 318.794697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:19.659954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:19.339373Z","time spent":"320.547529ms","remote":"127.0.0.1:34556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:26:19.660222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.898496ms","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-07T03:26:19.66025Z","caller":"traceutil/trace.go:171","msg":"trace[248570704] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:626; }","duration":"271.962268ms","start":"2026-05-07T03:26:19.38828Z","end":"2026-05-07T03:26:19.660243Z","steps":["trace[248570704] 'agreement among raft nodes before linearized reading' (duration: 270.33401ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:26:20.779937Z","caller":"traceutil/trace.go:171","msg":"trace[749021368] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"193.925181ms","start":"2026-05-07T03:26:20.585986Z","end":"2026-05-07T03:26:20.779912Z","steps":["trace[749021368] 'process raft request' (duration: 193.87423ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:26:20.779961Z","caller":"traceutil/trace.go:171","msg":"trace[1076507434] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"195.336195ms","start":"2026-05-07T03:26:20.584607Z","end":"2026-05-07T03:26:20.779943Z","steps":["trace[1076507434] 'process raft request' (duration: 195.109649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:22.180119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120486695s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:26:22.180196Z","caller":"traceutil/trace.go:171","msg":"trace[1212370732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"1.120643989s","start":"2026-05-07T03:26:21.059534Z","end":"2026-05-07T03:26:22.180178Z","steps":["trace[1212370732] 'range keys from in-memory index tree' (duration: 1.120407243s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:22.18025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:21.059508Z","time spent":"1.120734241s","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:26:34.929022Z","caller":"traceutil/trace.go:171","msg":"trace[710730224] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"182.502251ms","start":"2026-05-07T03:26:34.746499Z","end":"2026-05-07T03:26:34.929001Z","steps":["trace[710730224] 'process raft request' (duration: 182.386248ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:26:34.933287Z","caller":"traceutil/trace.go:171","msg":"trace[1862390101] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"186.590687ms","start":"2026-05-07T03:26:34.746669Z","end":"2026-05-07T03:26:34.933259Z","steps":["trace[1862390101] 'process raft request' (duration: 186.364472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:38.133327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071590897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:26:38.133326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.987919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T03:26:38.133409Z","caller":"traceutil/trace.go:171","msg":"trace[67653590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"1.07171737s","start":"2026-05-07T03:26:37.061672Z","end":"2026-05-07T03:26:38.13339Z","steps":["trace[67653590] 'range keys from in-memory index tree' (duration: 1.071482085s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:38.13346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:37.061642Z","time spent":"1.071797582s","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:26:38.133485Z","caller":"traceutil/trace.go:171","msg":"trace[251450380] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:787; }","duration":"316.165153ms","start":"2026-05-07T03:26:37.817243Z","end":"2026-05-07T03:26:38.133408Z","steps":["trace[251450380] 'range keys from in-memory index tree' (duration: 315.758684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:38.133578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:37.817215Z","time spent":"316.349038ms","remote":"127.0.0.1:34652","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-07T03:26:38.13333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.430901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ngrkw\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-07T03:26:38.133696Z","caller":"traceutil/trace.go:171","msg":"trace[836410781] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ngrkw; range_end:; response_count:1; response_revision:787; }","duration":"719.872191ms","start":"2026-05-07T03:26:37.413811Z","end":"2026-05-07T03:26:38.133683Z","steps":["trace[836410781] 'range keys from in-memory index tree' (duration: 719.161384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:38.133732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:37.413777Z","time spent":"719.944813ms","remote":"127.0.0.1:34556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ngrkw\" "} {"level":"info","ts":"2026-05-07T03:26:45.723274Z","caller":"traceutil/trace.go:171","msg":"trace[1532826126] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"124.655611ms","start":"2026-05-07T03:26:45.598592Z","end":"2026-05-07T03:26:45.723248Z","steps":["trace[1532826126] 'process raft request' (duration: 124.317243ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:26:53.66813Z","caller":"traceutil/trace.go:171","msg":"trace[1492690217] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"303.31782ms","start":"2026-05-07T03:26:53.364789Z","end":"2026-05-07T03:26:53.668107Z","steps":["trace[1492690217] 'process raft request' (duration: 303.181597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:53.668318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:53.364752Z","time spent":"303.455683ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:26:55.247799Z","caller":"traceutil/trace.go:171","msg":"trace[1691069143] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"225.387245ms","start":"2026-05-07T03:26:55.022367Z","end":"2026-05-07T03:26:55.247754Z","steps":["trace[1691069143] 'process raft request' (duration: 207.115213ms)","trace[1691069143] 'compare' (duration: 17.411862ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:26:55.247843Z","caller":"traceutil/trace.go:171","msg":"trace[740041430] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"155.968392ms","start":"2026-05-07T03:26:55.091848Z","end":"2026-05-07T03:26:55.247816Z","steps":["trace[740041430] 'process raft request' (duration: 155.847719ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:26:55.247872Z","caller":"traceutil/trace.go:171","msg":"trace[1594703602] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"160.200672ms","start":"2026-05-07T03:26:55.087635Z","end":"2026-05-07T03:26:55.247836Z","steps":["trace[1594703602] 'read index received' (duration: 141.821167ms)","trace[1594703602] 'applied index is now lower than readState.Index' (duration: 18.376765ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:26:55.247949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.825164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:26:55.248021Z","caller":"traceutil/trace.go:171","msg":"trace[1486904921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:940; }","duration":"189.941586ms","start":"2026-05-07T03:26:55.058063Z","end":"2026-05-07T03:26:55.248005Z","steps":["trace[1486904921] 'agreement among raft nodes before linearized reading' (duration: 189.794613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:55.248213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.878168ms","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-07T03:26:55.248384Z","caller":"traceutil/trace.go:171","msg":"trace[791122129] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:940; }","duration":"226.068252ms","start":"2026-05-07T03:26:55.022292Z","end":"2026-05-07T03:26:55.24836Z","steps":["trace[791122129] 'agreement among raft nodes before linearized reading' (duration: 225.772085ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:26:55.603182Z","caller":"traceutil/trace.go:171","msg":"trace[972322351] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"348.605923ms","start":"2026-05-07T03:26:55.254555Z","end":"2026-05-07T03:26:55.603161Z","steps":["trace[972322351] 'process raft request' (duration: 348.47517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:26:55.603326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:26:55.254531Z","time spent":"348.717765ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:26:56.374704Z","caller":"traceutil/trace.go:171","msg":"trace[1758171185] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"134.36289ms","start":"2026-05-07T03:26:56.240325Z","end":"2026-05-07T03:26:56.374687Z","steps":["trace[1758171185] 'process raft request' (duration: 134.260038ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:12.828598Z","caller":"traceutil/trace.go:171","msg":"trace[1138771086] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1032; }","duration":"171.254584ms","start":"2026-05-07T03:27:12.657322Z","end":"2026-05-07T03:27:12.828577Z","steps":["trace[1138771086] 'read index received' (duration: 171.07953ms)","trace[1138771086] 'applied index is now lower than readState.Index' (duration: 173.274µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:27:12.828617Z","caller":"traceutil/trace.go:171","msg":"trace[353985823] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"363.010153ms","start":"2026-05-07T03:27:12.465583Z","end":"2026-05-07T03:27:12.828593Z","steps":["trace[353985823] 'process raft request' (duration: 362.840019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:12.828746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:12.465558Z","time spent":"363.117926ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:27:12.828758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.433098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T03:27:12.828782Z","caller":"traceutil/trace.go:171","msg":"trace[620759674] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:998; }","duration":"171.51879ms","start":"2026-05-07T03:27:12.657256Z","end":"2026-05-07T03:27:12.828774Z","steps":["trace[620759674] 'agreement among raft nodes before linearized reading' (duration: 171.405268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:13.561986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.078068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:27:13.562129Z","caller":"traceutil/trace.go:171","msg":"trace[919700234] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"788.178079ms","start":"2026-05-07T03:27:12.773935Z","end":"2026-05-07T03:27:13.562113Z","steps":["trace[919700234] 'process raft request' (duration: 377.905846ms)","trace[919700234] 'compare' (duration: 409.845693ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:13.56217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:12.773915Z","time spent":"788.23624ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:27:13.562385Z","caller":"traceutil/trace.go:171","msg":"trace[1502053828] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1033; }","duration":"502.794043ms","start":"2026-05-07T03:27:13.059584Z","end":"2026-05-07T03:27:13.562378Z","steps":["trace[1502053828] 'read index received' (duration: 92.267625ms)","trace[1502053828] 'applied index is now lower than readState.Index' (duration: 410.525658ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:13.562432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:12.829714Z","time spent":"732.716945ms","remote":"127.0.0.1:34400","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-07T03:27:13.562564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.015318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:27:13.562583Z","caller":"traceutil/trace.go:171","msg":"trace[1503953606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:999; }","duration":"503.09436ms","start":"2026-05-07T03:27:13.059483Z","end":"2026-05-07T03:27:13.562577Z","steps":["trace[1503953606] 'agreement among raft nodes before linearized reading' (duration: 503.052919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:13.562601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:13.05946Z","time spent":"503.137151ms","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:27:13.562728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.173262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T03:27:13.562794Z","caller":"traceutil/trace.go:171","msg":"trace[215313104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:999; }","duration":"356.268334ms","start":"2026-05-07T03:27:13.206509Z","end":"2026-05-07T03:27:13.562777Z","steps":["trace[215313104] 'agreement among raft nodes before linearized reading' (duration: 356.078869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:13.562853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:13.206497Z","time spent":"356.341486ms","remote":"127.0.0.1:34652","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-07T03:27:13.562898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.600443ms","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-07T03:27:13.562921Z","caller":"traceutil/trace.go:171","msg":"trace[956802727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:999; }","duration":"174.648005ms","start":"2026-05-07T03:27:13.388267Z","end":"2026-05-07T03:27:13.562915Z","steps":["trace[956802727] 'agreement among raft nodes before linearized reading' (duration: 174.541632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:13.563001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.957261ms","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-07T03:27:13.563016Z","caller":"traceutil/trace.go:171","msg":"trace[1742669911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:999; }","duration":"171.986021ms","start":"2026-05-07T03:27:13.391025Z","end":"2026-05-07T03:27:13.563011Z","steps":["trace[1742669911] 'agreement among raft nodes before linearized reading' (duration: 171.94137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:13.56306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.854496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:27:13.563092Z","caller":"traceutil/trace.go:171","msg":"trace[899960364] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:999; }","duration":"252.907027ms","start":"2026-05-07T03:27:13.310175Z","end":"2026-05-07T03:27:13.563082Z","steps":["trace[899960364] 'agreement among raft nodes before linearized reading' (duration: 252.851046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:13.563213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.997158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:27:13.563234Z","caller":"traceutil/trace.go:171","msg":"trace[196244099] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:999; }","duration":"307.052349ms","start":"2026-05-07T03:27:13.256176Z","end":"2026-05-07T03:27:13.563228Z","steps":["trace[196244099] 'agreement among raft nodes before linearized reading' (duration: 306.995238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:13.563255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:13.256167Z","time spent":"307.08148ms","remote":"127.0.0.1:34428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-05-07T03:27:13.849171Z","caller":"traceutil/trace.go:171","msg":"trace[1778841886] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"283.425719ms","start":"2026-05-07T03:27:13.565703Z","end":"2026-05-07T03:27:13.849129Z","steps":["trace[1778841886] 'process raft request' (duration: 225.526509ms)","trace[1778841886] 'compare' (duration: 57.567412ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:27:13.849258Z","caller":"traceutil/trace.go:171","msg":"trace[60084969] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"282.950288ms","start":"2026-05-07T03:27:13.566271Z","end":"2026-05-07T03:27:13.849221Z","steps":["trace[60084969] 'process raft request' (duration: 282.740593ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:13.849564Z","caller":"traceutil/trace.go:171","msg":"trace[851758959] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"282.908007ms","start":"2026-05-07T03:27:13.566609Z","end":"2026-05-07T03:27:13.849517Z","steps":["trace[851758959] 'process raft request' (duration: 282.463246ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:13.849683Z","caller":"traceutil/trace.go:171","msg":"trace[324591179] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1035; }","duration":"204.199263ms","start":"2026-05-07T03:27:13.645471Z","end":"2026-05-07T03:27:13.84967Z","steps":["trace[324591179] 'read index received' (duration: 145.772541ms)","trace[324591179] 'applied index is now lower than readState.Index' (duration: 58.425462ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:27:13.849699Z","caller":"traceutil/trace.go:171","msg":"trace[1331692186] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"281.071113ms","start":"2026-05-07T03:27:13.568611Z","end":"2026-05-07T03:27:13.849682Z","steps":["trace[1331692186] 'process raft request' (duration: 280.808056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:13.849874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.383737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:27:13.849949Z","caller":"traceutil/trace.go:171","msg":"trace[2103844829] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1003; }","duration":"204.49603ms","start":"2026-05-07T03:27:13.645432Z","end":"2026-05-07T03:27:13.849928Z","steps":["trace[2103844829] 'agreement among raft nodes before linearized reading' (duration: 204.374218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:13.849971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.30247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T03:27:13.850015Z","caller":"traceutil/trace.go:171","msg":"trace[187679960] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1003; }","duration":"167.380731ms","start":"2026-05-07T03:27:13.682625Z","end":"2026-05-07T03:27:13.850005Z","steps":["trace[187679960] 'agreement among raft nodes before linearized reading' (duration: 167.200017ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:26.992816Z","caller":"traceutil/trace.go:171","msg":"trace[823667951] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"104.635765ms","start":"2026-05-07T03:27:26.88814Z","end":"2026-05-07T03:27:26.992776Z","steps":["trace[823667951] 'process raft request' (duration: 104.477262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:28.274463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.461191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:27:28.274582Z","caller":"traceutil/trace.go:171","msg":"trace[962198664] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"176.615594ms","start":"2026-05-07T03:27:28.097947Z","end":"2026-05-07T03:27:28.274563Z","steps":["trace[962198664] 'process raft request' (duration: 48.962568ms)","trace[962198664] 'compare' (duration: 127.351689ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:27:28.305392Z","caller":"traceutil/trace.go:171","msg":"trace[861407016] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"207.017247ms","start":"2026-05-07T03:27:28.098351Z","end":"2026-05-07T03:27:28.305368Z","steps":["trace[861407016] 'process raft request' (duration: 206.936195ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:28.305419Z","caller":"traceutil/trace.go:171","msg":"trace[150422433] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"207.182971ms","start":"2026-05-07T03:27:28.098207Z","end":"2026-05-07T03:27:28.30539Z","steps":["trace[150422433] 'process raft request' (duration: 206.985197ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:29.257421Z","caller":"traceutil/trace.go:171","msg":"trace[2107249722] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"257.224637ms","start":"2026-05-07T03:27:29.000166Z","end":"2026-05-07T03:27:29.257391Z","steps":["trace[2107249722] 'process raft request' (duration: 257.031482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:30.192245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:29.419605Z","time spent":"772.632201ms","remote":"127.0.0.1:49164","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-07T03:27:30.509147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134921593s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-05-07T03:27:30.50943Z","caller":"traceutil/trace.go:171","msg":"trace[189343918] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1089; }","duration":"1.450050698s","start":"2026-05-07T03:27:29.059324Z","end":"2026-05-07T03:27:30.509375Z","steps":["trace[189343918] 'read index received' (duration: 197.836402ms)","trace[189343918] 'applied index is now lower than readState.Index' (duration: 1.252212936s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:30.509566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.450229772s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:27:30.509593Z","caller":"traceutil/trace.go:171","msg":"trace[1541823538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1052; }","duration":"1.450298513s","start":"2026-05-07T03:27:29.059285Z","end":"2026-05-07T03:27:30.509584Z","steps":["trace[1541823538] 'agreement among raft nodes before linearized reading' (duration: 1.450238662s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:30.509626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:29.059269Z","time spent":"1.450350055s","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:27:30.509921Z","caller":"traceutil/trace.go:171","msg":"trace[45262210] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"793.03398ms","start":"2026-05-07T03:27:29.716877Z","end":"2026-05-07T03:27:30.50991Z","steps":["trace[45262210] 'process raft request' (duration: 792.370375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:30.510011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:29.716856Z","time spent":"793.100101ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:27:30.722211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.815352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:27:30.722289Z","caller":"traceutil/trace.go:171","msg":"trace[1481486869] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1053; }","duration":"722.953685ms","start":"2026-05-07T03:27:29.999317Z","end":"2026-05-07T03:27:30.722271Z","steps":["trace[1481486869] 'agreement among raft nodes before linearized reading' (duration: 510.577702ms)","trace[1481486869] 'count revisions from in-memory index tree' (duration: 212.249631ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:30.722326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:29.999294Z","time spent":"723.024226ms","remote":"127.0.0.1:34852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":28,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:27:30.722817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.080217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:27:30.722838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.234219ms","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-07T03:27:30.722884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.427346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T03:27:30.722888Z","caller":"traceutil/trace.go:171","msg":"trace[339487855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1053; }","duration":"212.21059ms","start":"2026-05-07T03:27:30.510666Z","end":"2026-05-07T03:27:30.722877Z","steps":["trace[339487855] 'range keys from in-memory index tree' (duration: 212.009105ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:30.722914Z","caller":"traceutil/trace.go:171","msg":"trace[1766152355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1053; }","duration":"414.497417ms","start":"2026-05-07T03:27:30.308407Z","end":"2026-05-07T03:27:30.722904Z","steps":["trace[1766152355] 'agreement among raft nodes before linearized reading' (duration: 201.621622ms)","trace[1766152355] 'range keys from in-memory index tree' (duration: 212.748543ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:27:30.72292Z","caller":"traceutil/trace.go:171","msg":"trace[572686504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1053; }","duration":"624.352501ms","start":"2026-05-07T03:27:30.098549Z","end":"2026-05-07T03:27:30.722901Z","steps":["trace[572686504] 'agreement among raft nodes before linearized reading' (duration: 411.509977ms)","trace[572686504] 'range keys from in-memory index tree' (duration: 212.6353ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:30.722938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:30.308377Z","time spent":"414.553808ms","remote":"127.0.0.1:34652","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-07T03:27:30.722958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:30.098537Z","time spent":"624.414293ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T03:27:30.72308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.876406ms","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-07T03:27:30.723098Z","caller":"traceutil/trace.go:171","msg":"trace[1854373757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1053; }","duration":"443.891377ms","start":"2026-05-07T03:27:30.279199Z","end":"2026-05-07T03:27:30.723091Z","steps":["trace[1854373757] 'agreement among raft nodes before linearized reading' (duration: 230.832147ms)","trace[1854373757] 'range keys from in-memory index tree' (duration: 213.002369ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:30.72312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:30.279123Z","time spent":"443.993449ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T03:27:30.72323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.130539ms","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-07T03:27:30.723258Z","caller":"traceutil/trace.go:171","msg":"trace[22402491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1053; }","duration":"414.177289ms","start":"2026-05-07T03:27:30.309073Z","end":"2026-05-07T03:27:30.72325Z","steps":["trace[22402491] 'agreement among raft nodes before linearized reading' (duration: 200.975296ms)","trace[22402491] 'range keys from in-memory index tree' (duration: 213.140662ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:30.723277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:30.309065Z","time spent":"414.20823ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T03:27:31.166667Z","caller":"traceutil/trace.go:171","msg":"trace[926626531] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"439.35055ms","start":"2026-05-07T03:27:30.727283Z","end":"2026-05-07T03:27:31.166634Z","steps":["trace[926626531] 'process raft request' (duration: 439.239037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:31.166779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:30.727268Z","time spent":"439.453742ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:27:31.795509Z","caller":"traceutil/trace.go:171","msg":"trace[446637700] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"1.06750441s","start":"2026-05-07T03:27:30.727985Z","end":"2026-05-07T03:27:31.795489Z","steps":["trace[446637700] 'process raft request' (duration: 1.067336416s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:31.795655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:30.727969Z","time spent":"1.067624122s","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:27:31.795757Z","caller":"traceutil/trace.go:171","msg":"trace[1790976280] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"1.066834143s","start":"2026-05-07T03:27:30.72888Z","end":"2026-05-07T03:27:31.795714Z","steps":["trace[1790976280] 'process raft request' (duration: 1.066579877s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:31.795818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:30.72883Z","time spent":"1.066961177s","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:27:31.795919Z","caller":"traceutil/trace.go:171","msg":"trace[1740726075] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"1.066858845s","start":"2026-05-07T03:27:30.729049Z","end":"2026-05-07T03:27:31.795908Z","steps":["trace[1740726075] 'process raft request' (duration: 1.066623599s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:31.795897Z","caller":"traceutil/trace.go:171","msg":"trace[706755217] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1092; }","duration":"1.063144677s","start":"2026-05-07T03:27:30.732728Z","end":"2026-05-07T03:27:31.795872Z","steps":["trace[706755217] 'read index received' (duration: 433.84047ms)","trace[706755217] 'applied index is now lower than readState.Index' (duration: 629.302517ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:31.795995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:30.729031Z","time spent":"1.066910265s","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:27:31.796035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.06330932s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:27:31.796065Z","caller":"traceutil/trace.go:171","msg":"trace[1732497775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1057; }","duration":"1.063357092s","start":"2026-05-07T03:27:30.732701Z","end":"2026-05-07T03:27:31.796058Z","steps":["trace[1732497775] 'agreement among raft nodes before linearized reading' (duration: 1.063308741s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:31.796087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.282855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-07T03:27:31.796093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:30.732688Z","time spent":"1.063397113s","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:27:31.796111Z","caller":"traceutil/trace.go:171","msg":"trace[1131163445] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1057; }","duration":"219.345576ms","start":"2026-05-07T03:27:31.576754Z","end":"2026-05-07T03:27:31.796099Z","steps":["trace[1131163445] 'agreement among raft nodes before linearized reading' (duration: 219.314245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:31.796167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.189675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T03:27:31.796209Z","caller":"traceutil/trace.go:171","msg":"trace[1025583617] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1057; }","duration":"534.276736ms","start":"2026-05-07T03:27:31.261923Z","end":"2026-05-07T03:27:31.796199Z","steps":["trace[1025583617] 'agreement among raft nodes before linearized reading' (duration: 534.110913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:31.796248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:31.261909Z","time spent":"534.331958ms","remote":"127.0.0.1:34652","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-07T03:27:32.126819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.798222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:27:32.126916Z","caller":"traceutil/trace.go:171","msg":"trace[183840596] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1096; }","duration":"321.72786ms","start":"2026-05-07T03:27:31.805171Z","end":"2026-05-07T03:27:32.126898Z","steps":["trace[183840596] 'read index received' (duration: 120.743663ms)","trace[183840596] 'applied index is now lower than readState.Index' (duration: 200.982717ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:32.126976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.803581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:27:32.127003Z","caller":"traceutil/trace.go:171","msg":"trace[2001541852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1058; }","duration":"321.855203ms","start":"2026-05-07T03:27:31.805136Z","end":"2026-05-07T03:27:32.126992Z","steps":["trace[2001541852] 'agreement among raft nodes before linearized reading' (duration: 321.806322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:32.127031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:31.805124Z","time spent":"321.900714ms","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:27:32.12702Z","caller":"traceutil/trace.go:171","msg":"trace[34577479] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"325.667632ms","start":"2026-05-07T03:27:31.801322Z","end":"2026-05-07T03:27:32.12699Z","steps":["trace[34577479] 'process raft request' (duration: 124.616194ms)","trace[34577479] 'compare' (duration: 200.636258ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:32.127742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:31.801301Z","time spent":"325.789895ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:27:32.847927Z","caller":"traceutil/trace.go:171","msg":"trace[1401405538] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1097; }","duration":"189.505268ms","start":"2026-05-07T03:27:32.658396Z","end":"2026-05-07T03:27:32.847902Z","steps":["trace[1401405538] 'read index received' (duration: 189.344754ms)","trace[1401405538] 'applied index is now lower than readState.Index' (duration: 159.174µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:27:32.847968Z","caller":"traceutil/trace.go:171","msg":"trace[925981298] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"332.850901ms","start":"2026-05-07T03:27:32.515089Z","end":"2026-05-07T03:27:32.847939Z","steps":["trace[925981298] 'process raft request' (duration: 332.616345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:32.84811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:32.515061Z","time spent":"332.971904ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:27:32.848128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.765432ms","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-07T03:27:32.848175Z","caller":"traceutil/trace.go:171","msg":"trace[489473835] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1059; }","duration":"180.880815ms","start":"2026-05-07T03:27:32.667275Z","end":"2026-05-07T03:27:32.848155Z","steps":["trace[489473835] 'agreement among raft nodes before linearized reading' (duration: 180.769832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:32.848202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.792314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T03:27:32.848331Z","caller":"traceutil/trace.go:171","msg":"trace[1242929870] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:1059; }","duration":"189.984978ms","start":"2026-05-07T03:27:32.658331Z","end":"2026-05-07T03:27:32.848316Z","steps":["trace[1242929870] 'agreement among raft nodes before linearized reading' (duration: 189.694412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:33.318286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.335228ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:38"} {"level":"info","ts":"2026-05-07T03:27:33.318383Z","caller":"traceutil/trace.go:171","msg":"trace[1064054379] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1098; }","duration":"288.359617ms","start":"2026-05-07T03:27:33.030005Z","end":"2026-05-07T03:27:33.318365Z","steps":["trace[1064054379] 'read index received' (duration: 52.041µs)","trace[1064054379] 'applied index is now lower than readState.Index' (duration: 288.306026ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:33.318422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:32.849066Z","time spent":"469.336644ms","remote":"127.0.0.1:34400","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-07T03:27:33.318467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.450919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:27:33.318499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.728932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:27:33.31851Z","caller":"traceutil/trace.go:171","msg":"trace[266735014] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1059; }","duration":"288.532551ms","start":"2026-05-07T03:27:33.029964Z","end":"2026-05-07T03:27:33.318497Z","steps":["trace[266735014] 'agreement among raft nodes before linearized reading' (duration: 288.446309ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:33.31853Z","caller":"traceutil/trace.go:171","msg":"trace[1859401301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1059; }","duration":"258.818883ms","start":"2026-05-07T03:27:33.0597Z","end":"2026-05-07T03:27:33.318518Z","steps":["trace[1859401301] 'agreement among raft nodes before linearized reading' (duration: 258.772302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:33.318652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.403169ms","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-07T03:27:33.318727Z","caller":"traceutil/trace.go:171","msg":"trace[1845364668] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1059; }","duration":"147.518862ms","start":"2026-05-07T03:27:33.171189Z","end":"2026-05-07T03:27:33.318708Z","steps":["trace[1845364668] 'agreement among raft nodes before linearized reading' (duration: 147.289136ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:33.98033Z","caller":"traceutil/trace.go:171","msg":"trace[1182844549] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1099; }","duration":"386.002949ms","start":"2026-05-07T03:27:33.594303Z","end":"2026-05-07T03:27:33.980306Z","steps":["trace[1182844549] 'read index received' (duration: 359.64661ms)","trace[1182844549] 'applied index is now lower than readState.Index' (duration: 26.355119ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:27:33.980463Z","caller":"traceutil/trace.go:171","msg":"trace[1215108663] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"660.978931ms","start":"2026-05-07T03:27:33.319472Z","end":"2026-05-07T03:27:33.980451Z","steps":["trace[1215108663] 'process raft request' (duration: 634.483849ms)","trace[1215108663] 'compare' (duration: 25.827486ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:33.980593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:33.319455Z","time spent":"661.055963ms","remote":"127.0.0.1:34400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:27:33.980666Z","caller":"traceutil/trace.go:171","msg":"trace[683675967] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"658.585835ms","start":"2026-05-07T03:27:33.32205Z","end":"2026-05-07T03:27:33.980636Z","steps":["trace[683675967] 'process raft request' (duration: 658.113794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:33.980693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.376697ms","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-07T03:27:33.980763Z","caller":"traceutil/trace.go:171","msg":"trace[1630926570] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1061; }","duration":"386.50398ms","start":"2026-05-07T03:27:33.594241Z","end":"2026-05-07T03:27:33.980745Z","steps":["trace[1630926570] 'agreement among raft nodes before linearized reading' (duration: 386.306065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:33.980801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:33.594222Z","time spent":"386.568882ms","remote":"127.0.0.1:47548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:27:33.980786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.149437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-07T03:27:33.980791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.556244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:27:33.980813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.711637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-07T03:27:33.980822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:33.322036Z","time spent":"658.703808ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:27:33.98085Z","caller":"traceutil/trace.go:171","msg":"trace[1633427316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1061; }","duration":"180.25999ms","start":"2026-05-07T03:27:33.800575Z","end":"2026-05-07T03:27:33.980835Z","steps":["trace[1633427316] 'agreement among raft nodes before linearized reading' (duration: 180.021814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:33.980831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.160188ms","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-07T03:27:33.980878Z","caller":"traceutil/trace.go:171","msg":"trace[608353666] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1061; }","duration":"156.725099ms","start":"2026-05-07T03:27:33.824131Z","end":"2026-05-07T03:27:33.980856Z","steps":["trace[608353666] 'agreement among raft nodes before linearized reading' (duration: 156.525504ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:33.980883Z","caller":"traceutil/trace.go:171","msg":"trace[39704198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1061; }","duration":"179.80521ms","start":"2026-05-07T03:27:33.801059Z","end":"2026-05-07T03:27:33.980865Z","steps":["trace[39704198] 'agreement among raft nodes before linearized reading' (duration: 179.564044ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:33.980914Z","caller":"traceutil/trace.go:171","msg":"trace[793682955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1061; }","duration":"180.296632ms","start":"2026-05-07T03:27:33.800601Z","end":"2026-05-07T03:27:33.980897Z","steps":["trace[793682955] 'agreement among raft nodes before linearized reading' (duration: 180.049806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:34.486788Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880765309207016,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T03:27:34.635831Z","caller":"traceutil/trace.go:171","msg":"trace[261222744] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"651.112749ms","start":"2026-05-07T03:27:33.984698Z","end":"2026-05-07T03:27:34.635811Z","steps":["trace[261222744] 'process raft request' (duration: 650.981066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:34.636141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:33.984685Z","time spent":"651.393026ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:27:34.86035Z","caller":"traceutil/trace.go:171","msg":"trace[574330251] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"875.344991ms","start":"2026-05-07T03:27:33.984979Z","end":"2026-05-07T03:27:34.860324Z","steps":["trace[574330251] 'process raft request' (duration: 831.853811ms)","trace[574330251] 'compare' (duration: 43.276225ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:27:34.860376Z","caller":"traceutil/trace.go:171","msg":"trace[1721191187] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"875.349982ms","start":"2026-05-07T03:27:33.984989Z","end":"2026-05-07T03:27:34.860339Z","steps":["trace[1721191187] 'process raft request' (duration: 875.230449ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:34.860366Z","caller":"traceutil/trace.go:171","msg":"trace[1203269519] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"150.160423ms","start":"2026-05-07T03:27:34.710188Z","end":"2026-05-07T03:27:34.860349Z","steps":["trace[1203269519] 'process raft request' (duration: 150.129142ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:34.860451Z","caller":"traceutil/trace.go:171","msg":"trace[2117829314] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1101; }","duration":"874.520912ms","start":"2026-05-07T03:27:33.985881Z","end":"2026-05-07T03:27:34.860402Z","steps":["trace[2117829314] 'read index received' (duration: 649.755048ms)","trace[2117829314] 'applied index is now lower than readState.Index' (duration: 224.763824ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:34.86049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:33.984959Z","time spent":"875.456634ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:27:34.86054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:33.984979Z","time spent":"875.468044ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:27:34.860623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.730227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-07T03:27:34.860692Z","caller":"traceutil/trace.go:171","msg":"trace[861639382] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1065; }","duration":"874.821659ms","start":"2026-05-07T03:27:33.985856Z","end":"2026-05-07T03:27:34.860677Z","steps":["trace[861639382] 'agreement among raft nodes before linearized reading' (duration: 874.695246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:34.860736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:33.985845Z","time spent":"874.882311ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-05-07T03:27:35.071961Z","caller":"traceutil/trace.go:171","msg":"trace[175137886] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1105; }","duration":"211.415971ms","start":"2026-05-07T03:27:34.860519Z","end":"2026-05-07T03:27:35.071935Z","steps":["trace[175137886] 'read index received' (duration: 134.243201ms)","trace[175137886] 'applied index is now lower than readState.Index' (duration: 77.17183ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:35.072148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.699401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:27:35.072232Z","caller":"traceutil/trace.go:171","msg":"trace[944512789] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1066; }","duration":"636.833294ms","start":"2026-05-07T03:27:34.435383Z","end":"2026-05-07T03:27:35.072216Z","steps":["trace[944512789] 'agreement among raft nodes before linearized reading' (duration: 636.704301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:35.07228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:34.43536Z","time spent":"636.909366ms","remote":"127.0.0.1:47644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-05-07T03:27:35.072483Z","caller":"traceutil/trace.go:171","msg":"trace[2076496664] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"220.107455ms","start":"2026-05-07T03:27:34.852363Z","end":"2026-05-07T03:27:35.07247Z","steps":["trace[2076496664] 'process raft request' (duration: 142.501144ms)","trace[2076496664] 'compare' (duration: 76.939945ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:35.072721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.23265ms","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-07T03:27:35.072778Z","caller":"traceutil/trace.go:171","msg":"trace[899333808] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1066; }","duration":"186.341273ms","start":"2026-05-07T03:27:34.886423Z","end":"2026-05-07T03:27:35.072764Z","steps":["trace[899333808] 'agreement among raft nodes before linearized reading' (duration: 186.22816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:35.073008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.781389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-07T03:27:35.073012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.636137ms","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-07T03:27:35.073064Z","caller":"traceutil/trace.go:171","msg":"trace[918306099] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1066; }","duration":"159.82854ms","start":"2026-05-07T03:27:34.913213Z","end":"2026-05-07T03:27:35.073042Z","steps":["trace[918306099] 'agreement among raft nodes before linearized reading' (duration: 159.763569ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:35.073096Z","caller":"traceutil/trace.go:171","msg":"trace[958588454] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1066; }","duration":"941.74762ms","start":"2026-05-07T03:27:34.131319Z","end":"2026-05-07T03:27:35.073067Z","steps":["trace[958588454] 'agreement among raft nodes before linearized reading' (duration: 941.518795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:35.073144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.072813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:27:35.073147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:34.131302Z","time spent":"941.834832ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-07T03:27:35.073185Z","caller":"traceutil/trace.go:171","msg":"trace[4116203] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1066; }","duration":"910.151659ms","start":"2026-05-07T03:27:34.163023Z","end":"2026-05-07T03:27:35.073174Z","steps":["trace[4116203] 'agreement among raft nodes before linearized reading' (duration: 909.080323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:35.073228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:34.163001Z","time spent":"910.207309ms","remote":"127.0.0.1:47634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:27:35.073238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.443015ms","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-07T03:27:35.073267Z","caller":"traceutil/trace.go:171","msg":"trace[51169009] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1066; }","duration":"134.495737ms","start":"2026-05-07T03:27:34.938764Z","end":"2026-05-07T03:27:35.073259Z","steps":["trace[51169009] 'agreement among raft nodes before linearized reading' (duration: 134.443855ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:35.602724Z","caller":"traceutil/trace.go:171","msg":"trace[1136515587] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"525.692745ms","start":"2026-05-07T03:27:35.077007Z","end":"2026-05-07T03:27:35.602699Z","steps":["trace[1136515587] 'process raft request' (duration: 525.519581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:35.602882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:35.076986Z","time spent":"525.821738ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:27:37.844407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.307389ms","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-07T03:27:37.844485Z","caller":"traceutil/trace.go:171","msg":"trace[691580354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1072; }","duration":"237.430032ms","start":"2026-05-07T03:27:37.607037Z","end":"2026-05-07T03:27:37.844467Z","steps":["trace[691580354] 'range keys from in-memory index tree' (duration: 237.129895ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:38.419903Z","caller":"traceutil/trace.go:171","msg":"trace[373418444] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"691.66061ms","start":"2026-05-07T03:27:37.728218Z","end":"2026-05-07T03:27:38.419878Z","steps":["trace[373418444] 'process raft request' (duration: 691.505017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:38.420075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:37.728193Z","time spent":"691.788924ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:27:38.596882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.477749ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-05-07T03:27:38.59732Z","caller":"traceutil/trace.go:171","msg":"trace[1374636903] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1112; }","duration":"535.752962ms","start":"2026-05-07T03:27:38.061548Z","end":"2026-05-07T03:27:38.597301Z","steps":["trace[1374636903] 'read index received' (duration: 358.067782ms)","trace[1374636903] 'applied index is now lower than readState.Index' (duration: 177.68335ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:27:38.597314Z","caller":"traceutil/trace.go:171","msg":"trace[537172769] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"748.151197ms","start":"2026-05-07T03:27:37.849139Z","end":"2026-05-07T03:27:38.59729Z","steps":["trace[537172769] 'process raft request' (duration: 747.832889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:38.59751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.949057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T03:27:38.597585Z","caller":"traceutil/trace.go:171","msg":"trace[857183625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1074; }","duration":"536.07617ms","start":"2026-05-07T03:27:38.061489Z","end":"2026-05-07T03:27:38.597565Z","steps":["trace[857183625] 'agreement among raft nodes before linearized reading' (duration: 535.874275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:38.597626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:38.061466Z","time spent":"536.150331ms","remote":"127.0.0.1:34652","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-07T03:27:38.597602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:37.849104Z","time spent":"748.332061ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:27:39.150555Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880765309207045,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:27:40.507468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.275399858s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:27:40.507636Z","caller":"traceutil/trace.go:171","msg":"trace[2024692174] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1115; }","duration":"1.857310222s","start":"2026-05-07T03:27:38.650312Z","end":"2026-05-07T03:27:40.507622Z","steps":["trace[2024692174] 'read index received' (duration: 581.70511ms)","trace[2024692174] 'applied index is now lower than readState.Index' (duration: 1.275604402s)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:27:40.507784Z","caller":"traceutil/trace.go:171","msg":"trace[1642953610] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"1.905454382s","start":"2026-05-07T03:27:38.602321Z","end":"2026-05-07T03:27:40.507776Z","steps":["trace[1642953610] 'process raft request' (duration: 629.684876ms)","trace[1642953610] 'compare' (duration: 1.275192442s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:40.507829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:38.602303Z","time spent":"1.905504854s","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:27:40.50794Z","caller":"traceutil/trace.go:171","msg":"trace[443747406] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"1.400447012s","start":"2026-05-07T03:27:39.107486Z","end":"2026-05-07T03:27:40.507933Z","steps":["trace[443747406] 'process raft request' (duration: 1.400055473s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:40.507977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:39.10746Z","time spent":"1.400498753s","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:27:40.508098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.857796943s","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-07T03:27:40.50812Z","caller":"traceutil/trace.go:171","msg":"trace[1596457116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1076; }","duration":"1.857862115s","start":"2026-05-07T03:27:38.650253Z","end":"2026-05-07T03:27:40.508115Z","steps":["trace[1596457116] 'agreement among raft nodes before linearized reading' (duration: 1.857788403s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:40.508137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:38.650221Z","time spent":"1.857912446s","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T03:27:40.508238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.623508897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:27:40.508258Z","caller":"traceutil/trace.go:171","msg":"trace[10074287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1076; }","duration":"1.623558978s","start":"2026-05-07T03:27:38.884695Z","end":"2026-05-07T03:27:40.508254Z","steps":["trace[10074287] 'agreement among raft nodes before linearized reading' (duration: 1.623510517s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:40.508275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:38.884677Z","time spent":"1.623593668s","remote":"127.0.0.1:34652","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-07T03:27:40.508433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.447989498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:27:40.508455Z","caller":"traceutil/trace.go:171","msg":"trace[585500278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1076; }","duration":"1.448039129s","start":"2026-05-07T03:27:39.060411Z","end":"2026-05-07T03:27:40.50845Z","steps":["trace[585500278] 'agreement among raft nodes before linearized reading' (duration: 1.448007658s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:40.50847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:39.060397Z","time spent":"1.44807035s","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:27:40.509196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.622676387s","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-07T03:27:40.509313Z","caller":"traceutil/trace.go:171","msg":"trace[1184538029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1076; }","duration":"1.6228246s","start":"2026-05-07T03:27:38.886469Z","end":"2026-05-07T03:27:40.509293Z","steps":["trace[1184538029] 'agreement among raft nodes before linearized reading' (duration: 1.622061303s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:40.50938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:38.886458Z","time spent":"1.622910652s","remote":"127.0.0.1:34652","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-07T03:27:40.962611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.107763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:27:40.962785Z","caller":"traceutil/trace.go:171","msg":"trace[563260356] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"449.498558ms","start":"2026-05-07T03:27:40.513261Z","end":"2026-05-07T03:27:40.96276Z","steps":["trace[563260356] 'process raft request' (duration: 123.14709ms)","trace[563260356] 'compare' (duration: 325.920518ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:40.962882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:40.513235Z","time spent":"449.603751ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:27:41.006966Z","caller":"traceutil/trace.go:171","msg":"trace[506023245] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"491.528536ms","start":"2026-05-07T03:27:40.515417Z","end":"2026-05-07T03:27:41.006946Z","steps":["trace[506023245] 'process raft request' (duration: 491.101515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:41.007068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.199341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:27:41.007187Z","caller":"traceutil/trace.go:171","msg":"trace[535146855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1079; }","duration":"489.355474ms","start":"2026-05-07T03:27:40.517816Z","end":"2026-05-07T03:27:41.007171Z","steps":["trace[535146855] 'agreement among raft nodes before linearized reading' (duration: 489.18882ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:41.00695Z","caller":"traceutil/trace.go:171","msg":"trace[166007454] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1117; }","duration":"489.072898ms","start":"2026-05-07T03:27:40.517846Z","end":"2026-05-07T03:27:41.006919Z","steps":["trace[166007454] 'read index received' (duration: 118.577903ms)","trace[166007454] 'applied index is now lower than readState.Index' (duration: 370.492455ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:27:41.006587Z","caller":"traceutil/trace.go:171","msg":"trace[904602870] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"491.168796ms","start":"2026-05-07T03:27:40.515392Z","end":"2026-05-07T03:27:41.006561Z","steps":["trace[904602870] 'process raft request' (duration: 490.707546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:41.007447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:40.515408Z","time spent":"491.629108ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:27:41.00753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:40.515376Z","time spent":"492.058748ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:27:41.007318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:40.517808Z","time spent":"489.493068ms","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:27:41.007755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.791964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T03:27:41.007851Z","caller":"traceutil/trace.go:171","msg":"trace[1829298763] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1079; }","duration":"405.947637ms","start":"2026-05-07T03:27:40.601887Z","end":"2026-05-07T03:27:41.007834Z","steps":["trace[1829298763] 'agreement among raft nodes before linearized reading' (duration: 405.580459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:41.007954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:40.601845Z","time spent":"406.095391ms","remote":"127.0.0.1:34652","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-07T03:27:43.293671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.721678ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:38"} {"level":"info","ts":"2026-05-07T03:27:43.293788Z","caller":"traceutil/trace.go:171","msg":"trace[1547273915] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1123; }","duration":"327.415504ms","start":"2026-05-07T03:27:42.966345Z","end":"2026-05-07T03:27:43.293761Z","steps":["trace[1547273915] 'read index received' (duration: 40.801µs)","trace[1547273915] 'applied index is now lower than readState.Index' (duration: 327.373143ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:43.293878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:42.659937Z","time spent":"633.937627ms","remote":"127.0.0.1:34400","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-07T03:27:43.294183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.324982ms","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-07T03:27:43.294267Z","caller":"traceutil/trace.go:171","msg":"trace[1280318226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1082; }","duration":"281.417044ms","start":"2026-05-07T03:27:43.012835Z","end":"2026-05-07T03:27:43.294253Z","steps":["trace[1280318226] 'agreement among raft nodes before linearized reading' (duration: 281.152168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:43.294409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.314365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:27:43.294493Z","caller":"traceutil/trace.go:171","msg":"trace[1373628821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1082; }","duration":"233.448729ms","start":"2026-05-07T03:27:43.061027Z","end":"2026-05-07T03:27:43.294476Z","steps":["trace[1373628821] 'agreement among raft nodes before linearized reading' (duration: 233.298175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:43.294487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.238866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T03:27:43.294572Z","caller":"traceutil/trace.go:171","msg":"trace[615882117] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1082; }","duration":"231.36104ms","start":"2026-05-07T03:27:43.063191Z","end":"2026-05-07T03:27:43.294552Z","steps":["trace[615882117] 'agreement among raft nodes before linearized reading' (duration: 231.106464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:43.294887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.538317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:27:43.294969Z","caller":"traceutil/trace.go:171","msg":"trace[1039558895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1082; }","duration":"281.66965ms","start":"2026-05-07T03:27:43.013281Z","end":"2026-05-07T03:27:43.294951Z","steps":["trace[1039558895] 'agreement among raft nodes before linearized reading' (duration: 281.159798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:43.29509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.317625ms","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-07T03:27:43.295323Z","caller":"traceutil/trace.go:171","msg":"trace[2007895663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1082; }","duration":"328.994911ms","start":"2026-05-07T03:27:42.966311Z","end":"2026-05-07T03:27:43.295305Z","steps":["trace[2007895663] 'agreement among raft nodes before linearized reading' (duration: 328.027408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:43.295383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:42.966297Z","time spent":"329.069163ms","remote":"127.0.0.1:34652","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-07T03:27:43.796772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880765309207067,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:27:44.297638Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880765309207067,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:27:44.445043Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.149399593s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T03:27:44.484805Z","caller":"traceutil/trace.go:171","msg":"trace[714621530] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"1.189249958s","start":"2026-05-07T03:27:43.295479Z","end":"2026-05-07T03:27:44.484729Z","steps":["trace[714621530] 'process raft request' (duration: 1.149875624s)","trace[714621530] 'compare' (duration: 38.007282ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:44.485027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:43.295458Z","time spent":"1.189457422s","remote":"127.0.0.1:34400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:27:44.57234Z","caller":"traceutil/trace.go:171","msg":"trace[1454476505] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"1.2728779s","start":"2026-05-07T03:27:43.299434Z","end":"2026-05-07T03:27:44.572312Z","steps":["trace[1454476505] 'process raft request' (duration: 1.272805979s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:44.57235Z","caller":"traceutil/trace.go:171","msg":"trace[32457655] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"1.272996403s","start":"2026-05-07T03:27:43.299312Z","end":"2026-05-07T03:27:44.572309Z","steps":["trace[32457655] 'process raft request' (duration: 1.272729937s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:44.572656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:43.299297Z","time spent":"1.27325426s","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:27:44.57249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:43.299407Z","time spent":"1.273007754s","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:27:44.572701Z","caller":"traceutil/trace.go:171","msg":"trace[1853756421] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1124; }","duration":"1.276817473s","start":"2026-05-07T03:27:43.295864Z","end":"2026-05-07T03:27:44.572681Z","steps":["trace[1853756421] 'read index received' (duration: 1.149458705s)","trace[1853756421] 'applied index is now lower than readState.Index' (duration: 127.357858ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:27:44.572821Z","caller":"traceutil/trace.go:171","msg":"trace[1276435192] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"1.272825019s","start":"2026-05-07T03:27:43.299982Z","end":"2026-05-07T03:27:44.572807Z","steps":["trace[1276435192] 'process raft request' (duration: 1.272295466s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:44.572897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:43.299965Z","time spent":"1.272893321s","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:27:44.572942Z","caller":"traceutil/trace.go:171","msg":"trace[1303958784] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"1.272310037s","start":"2026-05-07T03:27:43.300619Z","end":"2026-05-07T03:27:44.572929Z","steps":["trace[1303958784] 'process raft request' (duration: 1.271922998s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:44.573008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:43.300603Z","time spent":"1.272366389s","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:27:44.572898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.277031968s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:27:44.573078Z","caller":"traceutil/trace.go:171","msg":"trace[578233778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1087; }","duration":"1.277217542s","start":"2026-05-07T03:27:43.295837Z","end":"2026-05-07T03:27:44.573055Z","steps":["trace[578233778] 'agreement among raft nodes before linearized reading' (duration: 1.277015947s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:44.573104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.820456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-07T03:27:44.573151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:43.295811Z","time spent":"1.277320005s","remote":"127.0.0.1:34364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:27:44.573221Z","caller":"traceutil/trace.go:171","msg":"trace[1993834931] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1087; }","duration":"805.005651ms","start":"2026-05-07T03:27:43.768196Z","end":"2026-05-07T03:27:44.573202Z","steps":["trace[1993834931] 'agreement among raft nodes before linearized reading' (duration: 804.820677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:44.573275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:43.768159Z","time spent":"805.102454ms","remote":"127.0.0.1:34582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":43,"response size":28,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-05-07T03:27:44.859065Z","caller":"traceutil/trace.go:171","msg":"trace[1995763704] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1129; }","duration":"241.848245ms","start":"2026-05-07T03:27:44.617192Z","end":"2026-05-07T03:27:44.85904Z","steps":["trace[1995763704] 'read index received' (duration: 169.419745ms)","trace[1995763704] 'applied index is now lower than readState.Index' (duration: 72.42723ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:27:44.859307Z","caller":"traceutil/trace.go:171","msg":"trace[111973366] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"249.197408ms","start":"2026-05-07T03:27:44.610095Z","end":"2026-05-07T03:27:44.859292Z","steps":["trace[111973366] 'process raft request' (duration: 176.565784ms)","trace[111973366] 'compare' (duration: 72.179473ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:44.85968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.468999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T03:27:44.859741Z","caller":"traceutil/trace.go:171","msg":"trace[252194222] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1088; }","duration":"242.568322ms","start":"2026-05-07T03:27:44.617161Z","end":"2026-05-07T03:27:44.859729Z","steps":["trace[252194222] 'agreement among raft nodes before linearized reading' (duration: 241.973448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:45.115414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.808356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:27:45.115729Z","caller":"traceutil/trace.go:171","msg":"trace[996786111] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"252.021604ms","start":"2026-05-07T03:27:44.86368Z","end":"2026-05-07T03:27:45.115702Z","steps":["trace[996786111] 'process raft request' (duration: 98.803278ms)","trace[996786111] 'compare' (duration: 152.55257ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:27:45.115719Z","caller":"traceutil/trace.go:171","msg":"trace[2081661387] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"168.240708ms","start":"2026-05-07T03:27:44.947459Z","end":"2026-05-07T03:27:45.1157Z","steps":["trace[2081661387] 'process raft request' (duration: 168.129775ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:46.74622Z","caller":"traceutil/trace.go:171","msg":"trace[336327009] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"161.48932ms","start":"2026-05-07T03:27:46.584713Z","end":"2026-05-07T03:27:46.746202Z","steps":["trace[336327009] 'process raft request' (duration: 161.449279ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:46.746366Z","caller":"traceutil/trace.go:171","msg":"trace[1263076968] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"163.353703ms","start":"2026-05-07T03:27:46.582975Z","end":"2026-05-07T03:27:46.746328Z","steps":["trace[1263076968] 'process raft request' (duration: 84.705378ms)","trace[1263076968] 'compare' (duration: 78.127973ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:27:46.74646Z","caller":"traceutil/trace.go:171","msg":"trace[1465148157] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"161.794256ms","start":"2026-05-07T03:27:46.584656Z","end":"2026-05-07T03:27:46.74645Z","steps":["trace[1465148157] 'process raft request' (duration: 161.447238ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:47.338987Z","caller":"traceutil/trace.go:171","msg":"trace[1279733703] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1136; }","duration":"278.942746ms","start":"2026-05-07T03:27:47.059999Z","end":"2026-05-07T03:27:47.338942Z","steps":["trace[1279733703] 'read index received' (duration: 257.511103ms)","trace[1279733703] 'applied index is now lower than readState.Index' (duration: 21.430213ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:27:47.339005Z","caller":"traceutil/trace.go:171","msg":"trace[1943759005] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"473.724908ms","start":"2026-05-07T03:27:46.865254Z","end":"2026-05-07T03:27:47.338979Z","steps":["trace[1943759005] 'process raft request' (duration: 452.307865ms)","trace[1943759005] 'compare' (duration: 21.269429ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:47.339138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.12368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:27:47.33918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:27:46.865214Z","time spent":"473.859751ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:27:47.339202Z","caller":"traceutil/trace.go:171","msg":"trace[182681484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"279.258604ms","start":"2026-05-07T03:27:47.059932Z","end":"2026-05-07T03:27:47.339191Z","steps":["trace[182681484] 'agreement among raft nodes before linearized reading' (duration: 279.161131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:27:47.339363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.702326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T03:27:47.339487Z","caller":"traceutil/trace.go:171","msg":"trace[596051582] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1095; }","duration":"219.814519ms","start":"2026-05-07T03:27:47.119631Z","end":"2026-05-07T03:27:47.339446Z","steps":["trace[596051582] 'agreement among raft nodes before linearized reading' (duration: 219.487771ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:52.91716Z","caller":"traceutil/trace.go:171","msg":"trace[1904840149] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1155; }","duration":"145.186287ms","start":"2026-05-07T03:27:52.771956Z","end":"2026-05-07T03:27:52.917142Z","steps":["trace[1904840149] 'read index received' (duration: 70.372891ms)","trace[1904840149] 'applied index is now lower than readState.Index' (duration: 74.812546ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:27:52.917243Z","caller":"traceutil/trace.go:171","msg":"trace[229138897] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"146.1929ms","start":"2026-05-07T03:27:52.771017Z","end":"2026-05-07T03:27:52.91721Z","steps":["trace[229138897] 'process raft request' (duration: 119.194367ms)","trace[229138897] 'compare' (duration: 26.85286ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:27:52.917294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.33007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T03:27:52.917315Z","caller":"traceutil/trace.go:171","msg":"trace[1689266917] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1113; }","duration":"145.406262ms","start":"2026-05-07T03:27:52.771904Z","end":"2026-05-07T03:27:52.91731Z","steps":["trace[1689266917] 'agreement among raft nodes before linearized reading' (duration: 145.304829ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:58.928078Z","caller":"traceutil/trace.go:171","msg":"trace[380846159] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"188.628997ms","start":"2026-05-07T03:27:58.739431Z","end":"2026-05-07T03:27:58.92806Z","steps":["trace[380846159] 'process raft request' (duration: 188.3507ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:27:59.068875Z","caller":"traceutil/trace.go:171","msg":"trace[850610122] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"136.636316ms","start":"2026-05-07T03:27:58.932203Z","end":"2026-05-07T03:27:59.068839Z","steps":["trace[850610122] 'process raft request' (duration: 135.998771ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:28:01.199657Z","caller":"traceutil/trace.go:171","msg":"trace[227207766] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1232; }","duration":"122.006913ms","start":"2026-05-07T03:28:01.077625Z","end":"2026-05-07T03:28:01.199632Z","steps":["trace[227207766] 'read index received' (duration: 121.303327ms)","trace[227207766] 'applied index is now lower than readState.Index' (duration: 702.526µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:28:01.199882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.244639ms","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-07T03:28:01.199926Z","caller":"traceutil/trace.go:171","msg":"trace[962236429] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1186; }","duration":"122.334911ms","start":"2026-05-07T03:28:01.077579Z","end":"2026-05-07T03:28:01.199914Z","steps":["trace[962236429] 'agreement among raft nodes before linearized reading' (duration: 122.184537ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:28:01.199967Z","caller":"traceutil/trace.go:171","msg":"trace[2124973600] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"123.086039ms","start":"2026-05-07T03:28:01.076605Z","end":"2026-05-07T03:28:01.199691Z","steps":["trace[2124973600] 'process raft request' (duration: 122.381022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:01.199982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.7679ms","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-07T03:28:01.200149Z","caller":"traceutil/trace.go:171","msg":"trace[356319640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1186; }","duration":"110.976714ms","start":"2026-05-07T03:28:01.089161Z","end":"2026-05-07T03:28:01.200138Z","steps":["trace[356319640] 'agreement among raft nodes before linearized reading' (duration: 110.671457ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:28:03.395439Z","caller":"traceutil/trace.go:171","msg":"trace[1217241568] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"154.050505ms","start":"2026-05-07T03:28:03.241362Z","end":"2026-05-07T03:28:03.395413Z","steps":["trace[1217241568] 'process raft request' (duration: 130.440791ms)","trace[1217241568] 'compare' (duration: 23.475991ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:28:07.079625Z","caller":"traceutil/trace.go:171","msg":"trace[1135915040] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"103.480248ms","start":"2026-05-07T03:28:06.976122Z","end":"2026-05-07T03:28:07.079602Z","steps":["trace[1135915040] 'process raft request' (duration: 103.309624ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:28:09.252184Z","caller":"traceutil/trace.go:171","msg":"trace[1255227340] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"164.494ms","start":"2026-05-07T03:28:09.087652Z","end":"2026-05-07T03:28:09.252146Z","steps":["trace[1255227340] 'process raft request' (duration: 160.007365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:10.862225Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.427301274s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-07T03:28:11.844514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.445546298s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:28:11.844721Z","caller":"traceutil/trace.go:171","msg":"trace[1977152834] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"2.409885921s","start":"2026-05-07T03:28:09.434817Z","end":"2026-05-07T03:28:11.844703Z","steps":["trace[1977152834] 'process raft request' (duration: 2.409800949s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:11.844783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:09.434795Z","time spent":"2.409956633s","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:28:11.844959Z","caller":"traceutil/trace.go:171","msg":"trace[1609325648] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"2.578908788s","start":"2026-05-07T03:28:09.266039Z","end":"2026-05-07T03:28:11.844948Z","steps":["trace[1609325648] 'process raft request' (duration: 132.834407ms)","trace[1609325648] 'compare' (duration: 2.444893183s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:28:11.845007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:09.266021Z","time spent":"2.57896062s","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:28:11.845118Z","caller":"traceutil/trace.go:171","msg":"trace[718753213] linearizableReadLoop","detail":"{readStateIndex:1274; appliedIndex:1273; }","duration":"2.546280033s","start":"2026-05-07T03:28:09.298828Z","end":"2026-05-07T03:28:11.845109Z","steps":["trace[718753213] 'read index received' (duration: 99.919815ms)","trace[718753213] 'applied index is now lower than readState.Index' (duration: 2.446359188s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:28:11.845394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.546564559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T03:28:11.845423Z","caller":"traceutil/trace.go:171","msg":"trace[1911034087] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1226; }","duration":"2.546644881s","start":"2026-05-07T03:28:09.298769Z","end":"2026-05-07T03:28:11.845414Z","steps":["trace[1911034087] 'agreement among raft nodes before linearized reading' (duration: 2.546537248s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:11.84545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:09.298746Z","time spent":"2.546697503s","remote":"127.0.0.1:34652","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-07T03:28:11.845626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.42430762s","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-07T03:28:11.845652Z","caller":"traceutil/trace.go:171","msg":"trace[352561162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1226; }","duration":"2.424373181s","start":"2026-05-07T03:28:09.421271Z","end":"2026-05-07T03:28:11.845644Z","steps":["trace[352561162] 'agreement among raft nodes before linearized reading' (duration: 2.424287709s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:11.845676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:09.421233Z","time spent":"2.424420473s","remote":"127.0.0.1:34652","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-07T03:28:11.845927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.529895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T03:28:11.845955Z","caller":"traceutil/trace.go:171","msg":"trace[167996323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1226; }","duration":"589.596726ms","start":"2026-05-07T03:28:11.25635Z","end":"2026-05-07T03:28:11.845947Z","steps":["trace[167996323] 'agreement among raft nodes before linearized reading' (duration: 589.493664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:11.845981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:11.25633Z","time spent":"589.644597ms","remote":"127.0.0.1:34652","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-07T03:28:11.846054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.785147191s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:28:11.846075Z","caller":"traceutil/trace.go:171","msg":"trace[35383271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1226; }","duration":"1.785204382s","start":"2026-05-07T03:28:10.060863Z","end":"2026-05-07T03:28:11.846068Z","steps":["trace[35383271] 'agreement among raft nodes before linearized reading' (duration: 1.785173552s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:11.846098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:10.060843Z","time spent":"1.785250294s","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:28:11.846411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.35101633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T03:28:11.84652Z","caller":"traceutil/trace.go:171","msg":"trace[812728557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1226; }","duration":"2.351184544s","start":"2026-05-07T03:28:09.495312Z","end":"2026-05-07T03:28:11.846496Z","steps":["trace[812728557] 'agreement among raft nodes before linearized reading' (duration: 2.350836326s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:11.84657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:09.495291Z","time spent":"2.351268256s","remote":"127.0.0.1:34652","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":"info","ts":"2026-05-07T03:28:16.061547Z","caller":"traceutil/trace.go:171","msg":"trace[1260359371] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1290; }","duration":"148.257069ms","start":"2026-05-07T03:28:15.91327Z","end":"2026-05-07T03:28:16.061528Z","steps":["trace[1260359371] 'read index received' (duration: 148.127916ms)","trace[1260359371] 'applied index is now lower than readState.Index' (duration: 128.263µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:28:16.061653Z","caller":"traceutil/trace.go:171","msg":"trace[313695288] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"173.876251ms","start":"2026-05-07T03:28:15.887767Z","end":"2026-05-07T03:28:16.061643Z","steps":["trace[313695288] 'process raft request' (duration: 173.642155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:16.061784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.175387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:28:16.061821Z","caller":"traceutil/trace.go:171","msg":"trace[1877593664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1241; }","duration":"122.243859ms","start":"2026-05-07T03:28:15.939568Z","end":"2026-05-07T03:28:16.061812Z","steps":["trace[1877593664] 'agreement among raft nodes before linearized reading' (duration: 122.136936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:16.061916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.644358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T03:28:16.061938Z","caller":"traceutil/trace.go:171","msg":"trace[1396906910] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1241; }","duration":"148.69016ms","start":"2026-05-07T03:28:15.913243Z","end":"2026-05-07T03:28:16.061933Z","steps":["trace[1396906910] 'agreement among raft nodes before linearized reading' (duration: 148.635448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:16.062092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.993052ms","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-07T03:28:16.062115Z","caller":"traceutil/trace.go:171","msg":"trace[1992026165] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1241; }","duration":"122.024813ms","start":"2026-05-07T03:28:15.940086Z","end":"2026-05-07T03:28:16.06211Z","steps":["trace[1992026165] 'agreement among raft nodes before linearized reading' (duration: 121.970132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:20.344631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.270646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:28:20.344709Z","caller":"traceutil/trace.go:171","msg":"trace[1917930401] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1306; }","duration":"182.984275ms","start":"2026-05-07T03:28:20.161712Z","end":"2026-05-07T03:28:20.344696Z","steps":["trace[1917930401] 'read index received' (duration: 32.587145ms)","trace[1917930401] 'applied index is now lower than readState.Index' (duration: 150.396069ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:28:20.344785Z","caller":"traceutil/trace.go:171","msg":"trace[686862483] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"187.391127ms","start":"2026-05-07T03:28:20.157382Z","end":"2026-05-07T03:28:20.344773Z","steps":["trace[686862483] 'process raft request' (duration: 36.904906ms)","trace[686862483] 'compare' (duration: 150.145353ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:28:20.344993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.280941ms","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-07T03:28:20.345016Z","caller":"traceutil/trace.go:171","msg":"trace[1834148691] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1256; }","duration":"183.323632ms","start":"2026-05-07T03:28:20.161686Z","end":"2026-05-07T03:28:20.34501Z","steps":["trace[1834148691] 'agreement among raft nodes before linearized reading' (duration: 183.23372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:20.34501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.646897ms","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-07T03:28:20.345037Z","caller":"traceutil/trace.go:171","msg":"trace[76827958] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1256; }","duration":"182.687967ms","start":"2026-05-07T03:28:20.162342Z","end":"2026-05-07T03:28:20.34503Z","steps":["trace[76827958] 'agreement among raft nodes before linearized reading' (duration: 182.615576ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:28:20.546735Z","caller":"traceutil/trace.go:171","msg":"trace[307456467] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"196.346018ms","start":"2026-05-07T03:28:20.350371Z","end":"2026-05-07T03:28:20.546717Z","steps":["trace[307456467] 'process raft request' (duration: 196.310977ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:28:20.547055Z","caller":"traceutil/trace.go:171","msg":"trace[1947351882] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"198.13888ms","start":"2026-05-07T03:28:20.348909Z","end":"2026-05-07T03:28:20.547048Z","steps":["trace[1947351882] 'process raft request' (duration: 125.557547ms)","trace[1947351882] 'compare' (duration: 72.101122ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:28:25.003796Z","caller":"traceutil/trace.go:171","msg":"trace[1078911703] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1329; }","duration":"369.854269ms","start":"2026-05-07T03:28:24.63392Z","end":"2026-05-07T03:28:25.003774Z","steps":["trace[1078911703] 'read index received' (duration: 369.603743ms)","trace[1078911703] 'applied index is now lower than readState.Index' (duration: 249.516µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:28:25.003889Z","caller":"traceutil/trace.go:171","msg":"trace[780864062] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"381.239866ms","start":"2026-05-07T03:28:24.62262Z","end":"2026-05-07T03:28:25.00386Z","steps":["trace[780864062] 'process raft request' (duration: 381.00673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:25.004029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:24.622605Z","time spent":"381.348979ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:28:25.004064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.597746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-cjgnw\" ","response":"range_response_count:1 size:10490"} {"level":"warn","ts":"2026-05-07T03:28:25.004063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.125916ms","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-07T03:28:25.004114Z","caller":"traceutil/trace.go:171","msg":"trace[2117992671] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-cjgnw; range_end:; response_count:1; response_revision:1278; }","duration":"194.701119ms","start":"2026-05-07T03:28:24.809401Z","end":"2026-05-07T03:28:25.004102Z","steps":["trace[2117992671] 'agreement among raft nodes before linearized reading' (duration: 194.512574ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:28:25.004135Z","caller":"traceutil/trace.go:171","msg":"trace[1278215218] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1278; }","duration":"370.225508ms","start":"2026-05-07T03:28:24.633896Z","end":"2026-05-07T03:28:25.004121Z","steps":["trace[1278215218] 'agreement among raft nodes before linearized reading' (duration: 370.009933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:25.004169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:24.633885Z","time spent":"370.275839ms","remote":"127.0.0.1:34652","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":"info","ts":"2026-05-07T03:28:25.167715Z","caller":"traceutil/trace.go:171","msg":"trace[1002721664] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"160.063433ms","start":"2026-05-07T03:28:25.007621Z","end":"2026-05-07T03:28:25.167685Z","steps":["trace[1002721664] 'process raft request' (duration: 85.076737ms)","trace[1002721664] 'compare' (duration: 74.832532ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:28:25.216286Z","caller":"traceutil/trace.go:171","msg":"trace[1504972870] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1330; }","duration":"158.064312ms","start":"2026-05-07T03:28:25.058202Z","end":"2026-05-07T03:28:25.216266Z","steps":["trace[1504972870] 'read index received' (duration: 34.577832ms)","trace[1504972870] 'applied index is now lower than readState.Index' (duration: 123.48566ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:28:25.216321Z","caller":"traceutil/trace.go:171","msg":"trace[572704013] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"202.212208ms","start":"2026-05-07T03:28:25.014089Z","end":"2026-05-07T03:28:25.216301Z","steps":["trace[572704013] 'process raft request' (duration: 202.047184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:25.216387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.167904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:28:25.216421Z","caller":"traceutil/trace.go:171","msg":"trace[335940370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1280; }","duration":"158.252657ms","start":"2026-05-07T03:28:25.058163Z","end":"2026-05-07T03:28:25.216416Z","steps":["trace[335940370] 'agreement among raft nodes before linearized reading' (duration: 158.176255ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:28:25.385468Z","caller":"traceutil/trace.go:171","msg":"trace[649415122] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"160.81818ms","start":"2026-05-07T03:28:25.224627Z","end":"2026-05-07T03:28:25.385445Z","steps":["trace[649415122] 'process raft request' (duration: 160.595635ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:28:25.385841Z","caller":"traceutil/trace.go:171","msg":"trace[1984201895] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"156.432158ms","start":"2026-05-07T03:28:25.229312Z","end":"2026-05-07T03:28:25.385744Z","steps":["trace[1984201895] 'process raft request' (duration: 156.053619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:26.679844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.582784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:28:26.679918Z","caller":"traceutil/trace.go:171","msg":"trace[1453335217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1284; }","duration":"620.695437ms","start":"2026-05-07T03:28:26.059204Z","end":"2026-05-07T03:28:26.6799Z","steps":["trace[1453335217] 'range keys from in-memory index tree' (duration: 620.517723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:26.679918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.372438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-07T03:28:26.679952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:26.059192Z","time spent":"620.753068ms","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:28:26.679987Z","caller":"traceutil/trace.go:171","msg":"trace[1623562631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1284; }","duration":"664.483031ms","start":"2026-05-07T03:28:26.015486Z","end":"2026-05-07T03:28:26.679969Z","steps":["trace[1623562631] 'range keys from in-memory index tree' (duration: 664.165584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:26.680027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:26.015467Z","time spent":"664.552183ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T03:28:26.680073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.281853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:28:26.680166Z","caller":"traceutil/trace.go:171","msg":"trace[702882993] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1336; }","duration":"448.997721ms","start":"2026-05-07T03:28:26.231151Z","end":"2026-05-07T03:28:26.680148Z","steps":["trace[702882993] 'read index received' (duration: 151.455872ms)","trace[702882993] 'applied index is now lower than readState.Index' (duration: 297.540199ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:28:26.680177Z","caller":"traceutil/trace.go:171","msg":"trace[1670429072] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"576.601646ms","start":"2026-05-07T03:28:26.103552Z","end":"2026-05-07T03:28:26.680154Z","steps":["trace[1670429072] 'process raft request' (duration: 279.151949ms)","trace[1670429072] 'compare' (duration: 297.026027ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:28:26.680287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:26.103523Z","time spent":"576.703538ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:28:26.680358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.198376ms","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-07T03:28:26.680367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.908311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:28:26.6804Z","caller":"traceutil/trace.go:171","msg":"trace[1027207531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1285; }","duration":"310.940982ms","start":"2026-05-07T03:28:26.369452Z","end":"2026-05-07T03:28:26.680393Z","steps":["trace[1027207531] 'agreement among raft nodes before linearized reading' (duration: 310.836809ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:28:26.6804Z","caller":"traceutil/trace.go:171","msg":"trace[1938306115] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1285; }","duration":"449.245017ms","start":"2026-05-07T03:28:26.231143Z","end":"2026-05-07T03:28:26.680388Z","steps":["trace[1938306115] 'agreement among raft nodes before linearized reading' (duration: 449.108044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:26.680417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:26.369396Z","time spent":"311.017174ms","remote":"127.0.0.1:34652","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-07T03:28:26.680443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:26.231041Z","time spent":"449.389451ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T03:28:26.973738Z","caller":"traceutil/trace.go:171","msg":"trace[188378790] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"287.958474ms","start":"2026-05-07T03:28:26.685752Z","end":"2026-05-07T03:28:26.97371Z","steps":["trace[188378790] 'process raft request' (duration: 285.944807ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:28:27.017688Z","caller":"traceutil/trace.go:171","msg":"trace[1522732557] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"330.039049ms","start":"2026-05-07T03:28:26.687595Z","end":"2026-05-07T03:28:27.017634Z","steps":["trace[1522732557] 'process raft request' (duration: 329.908066ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:28:27.017724Z","caller":"traceutil/trace.go:171","msg":"trace[1884688154] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"331.009541ms","start":"2026-05-07T03:28:26.686615Z","end":"2026-05-07T03:28:27.017624Z","steps":["trace[1884688154] 'process raft request' (duration: 330.715634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:27.017977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:26.687586Z","time spent":"330.201133ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:28:27.018198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:26.686595Z","time spent":"331.282278ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:28:28.559942Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880765309207536,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:28:28.989665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.422276ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-05-07T03:28:28.990341Z","caller":"traceutil/trace.go:171","msg":"trace[3596477] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1342; }","duration":"930.870611ms","start":"2026-05-07T03:28:28.059451Z","end":"2026-05-07T03:28:28.990322Z","steps":["trace[3596477] 'read index received' (duration: 620.767439ms)","trace[3596477] 'applied index is now lower than readState.Index' (duration: 310.101772ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:28:28.990352Z","caller":"traceutil/trace.go:171","msg":"trace[604574922] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"983.511631ms","start":"2026-05-07T03:28:28.006821Z","end":"2026-05-07T03:28:28.990332Z","steps":["trace[604574922] 'process raft request' (duration: 982.924458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:28.990669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:28.006806Z","time spent":"983.808779ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:28:29.177284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117806123s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:28:29.177379Z","caller":"traceutil/trace.go:171","msg":"trace[60070355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1291; }","duration":"1.117948486s","start":"2026-05-07T03:28:28.0594Z","end":"2026-05-07T03:28:29.177349Z","steps":["trace[60070355] 'agreement among raft nodes before linearized reading' (duration: 931.036085ms)","trace[60070355] 'range keys from in-memory index tree' (duration: 186.779188ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:28:29.177385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.180555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-07T03:28:29.177428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:28.059373Z","time spent":"1.118044199s","remote":"127.0.0.1:34364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:28:29.177458Z","caller":"traceutil/trace.go:171","msg":"trace[1744543898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1291; }","duration":"198.297247ms","start":"2026-05-07T03:28:28.979143Z","end":"2026-05-07T03:28:29.17744Z","steps":["trace[1744543898] 'range keys from in-memory index tree' (duration: 186.723507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:29.177818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.425879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:28:29.177923Z","caller":"traceutil/trace.go:171","msg":"trace[840984915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1291; }","duration":"153.546311ms","start":"2026-05-07T03:28:29.024355Z","end":"2026-05-07T03:28:29.177901Z","steps":["trace[840984915] 'range keys from in-memory index tree' (duration: 153.302805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:29.177549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075462873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:28:29.178095Z","caller":"traceutil/trace.go:171","msg":"trace[1545984877] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1291; }","duration":"1.076042416s","start":"2026-05-07T03:28:28.102029Z","end":"2026-05-07T03:28:29.178072Z","steps":["trace[1545984877] 'agreement among raft nodes before linearized reading' (duration: 888.428098ms)","trace[1545984877] 'count revisions from in-memory index tree' (duration: 187.067506ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:28:29.178161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:28.102008Z","time spent":"1.076133308s","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":28,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:28:29.177554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.483403ms","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-07T03:28:29.178299Z","caller":"traceutil/trace.go:171","msg":"trace[478319896] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1291; }","duration":"155.292522ms","start":"2026-05-07T03:28:29.022993Z","end":"2026-05-07T03:28:29.178285Z","steps":["trace[478319896] 'range keys from in-memory index tree' (duration: 154.223107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:29.177677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.28381ms","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-07T03:28:29.178532Z","caller":"traceutil/trace.go:171","msg":"trace[987849745] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1291; }","duration":"123.165111ms","start":"2026-05-07T03:28:29.055355Z","end":"2026-05-07T03:28:29.17852Z","steps":["trace[987849745] 'range keys from in-memory index tree' (duration: 122.196008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:29.177753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02532949s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:28:29.178607Z","caller":"traceutil/trace.go:171","msg":"trace[1883243799] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1291; }","duration":"1.0261835s","start":"2026-05-07T03:28:28.152404Z","end":"2026-05-07T03:28:29.178588Z","steps":["trace[1883243799] 'agreement among raft nodes before linearized reading' (duration: 838.087991ms)","trace[1883243799] 'count revisions from in-memory index tree' (duration: 187.224299ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:28:29.178663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:28.152332Z","time spent":"1.026312943s","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":26,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-05-07T03:28:29.288541Z","caller":"traceutil/trace.go:171","msg":"trace[1935517805] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"105.049757ms","start":"2026-05-07T03:28:29.183464Z","end":"2026-05-07T03:28:29.288514Z","steps":["trace[1935517805] 'process raft request' (duration: 104.891854ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:28:29.922843Z","caller":"traceutil/trace.go:171","msg":"trace[328766705] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"739.336982ms","start":"2026-05-07T03:28:29.183488Z","end":"2026-05-07T03:28:29.922825Z","steps":["trace[328766705] 'process raft request' (duration: 739.167188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:29.922986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:29.183461Z","time spent":"739.441635ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:28:29.923218Z","caller":"traceutil/trace.go:171","msg":"trace[1413754870] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"739.599809ms","start":"2026-05-07T03:28:29.1836Z","end":"2026-05-07T03:28:29.923199Z","steps":["trace[1413754870] 'process raft request' (duration: 739.186899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:29.923309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:29.183587Z","time spent":"739.673ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:28:29.923349Z","caller":"traceutil/trace.go:171","msg":"trace[1699001008] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"739.24504ms","start":"2026-05-07T03:28:29.18409Z","end":"2026-05-07T03:28:29.923335Z","steps":["trace[1699001008] 'process raft request' (duration: 738.971824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:29.923403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:29.184082Z","time spent":"739.296491ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:28:29.923234Z","caller":"traceutil/trace.go:171","msg":"trace[558898284] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1344; }","duration":"737.561561ms","start":"2026-05-07T03:28:29.185649Z","end":"2026-05-07T03:28:29.923211Z","steps":["trace[558898284] 'read index received' (duration: 102.692102ms)","trace[558898284] 'applied index is now lower than readState.Index' (duration: 634.868159ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:28:29.923513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.859208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:28:29.923648Z","caller":"traceutil/trace.go:171","msg":"trace[1185369300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1295; }","duration":"738.002491ms","start":"2026-05-07T03:28:29.185628Z","end":"2026-05-07T03:28:29.923631Z","steps":["trace[1185369300] 'agreement among raft nodes before linearized reading' (duration: 737.850207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:29.923746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:29.18562Z","time spent":"738.114433ms","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:28:29.982768Z","caller":"traceutil/trace.go:171","msg":"trace[1500946509] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"652.281496ms","start":"2026-05-07T03:28:29.330456Z","end":"2026-05-07T03:28:29.982738Z","steps":["trace[1500946509] 'process raft request' (duration: 652.075252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:29.982865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.879308ms","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-07T03:28:29.982962Z","caller":"traceutil/trace.go:171","msg":"trace[1441020478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1296; }","duration":"788.0123ms","start":"2026-05-07T03:28:29.194931Z","end":"2026-05-07T03:28:29.982943Z","steps":["trace[1441020478] 'agreement among raft nodes before linearized reading' (duration: 787.758134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:29.983009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:29.194895Z","time spent":"788.104503ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T03:28:29.983169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:29.330431Z","time spent":"652.493821ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:28:30.156906Z","caller":"traceutil/trace.go:171","msg":"trace[1410388807] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"168.980612ms","start":"2026-05-07T03:28:29.987889Z","end":"2026-05-07T03:28:30.156869Z","steps":["trace[1410388807] 'process raft request' (duration: 76.97528ms)","trace[1410388807] 'compare' (duration: 91.594943ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:28:30.791068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.292972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:28:30.791177Z","caller":"traceutil/trace.go:171","msg":"trace[1632879738] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"566.067269ms","start":"2026-05-07T03:28:30.225088Z","end":"2026-05-07T03:28:30.791155Z","steps":["trace[1632879738] 'process raft request' (duration: 53.620354ms)","trace[1632879738] 'compare' (duration: 511.982474ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:28:30.791232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:30.225067Z","time spent":"566.142972ms","remote":"127.0.0.1:34454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":888,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:28:31.561523Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880765309207552,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:28:32.019021Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.022921685s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T03:28:32.020503Z","caller":"traceutil/trace.go:171","msg":"trace[1852824448] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"959.965632ms","start":"2026-05-07T03:28:31.060506Z","end":"2026-05-07T03:28:32.020472Z","steps":["trace[1852824448] 'read index received' (duration: 958.712333ms)","trace[1852824448] 'applied index is now lower than readState.Index' (duration: 1.252219ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:28:32.020607Z","caller":"traceutil/trace.go:171","msg":"trace[1445492428] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"1.024618714s","start":"2026-05-07T03:28:30.995947Z","end":"2026-05-07T03:28:32.020566Z","steps":["trace[1445492428] 'process raft request' (duration: 1.023352665s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:32.020674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.144376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:28:32.020796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:30.995912Z","time spent":"1.024777698s","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:28:32.021181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.815856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T03:28:32.021234Z","caller":"traceutil/trace.go:171","msg":"trace[161673359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1299; }","duration":"728.950559ms","start":"2026-05-07T03:28:31.29227Z","end":"2026-05-07T03:28:32.02122Z","steps":["trace[161673359] 'agreement among raft nodes before linearized reading' (duration: 728.332774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:32.021283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:31.29225Z","time spent":"729.019011ms","remote":"127.0.0.1:34652","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-07T03:28:32.021952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.792046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:28:32.021995Z","caller":"traceutil/trace.go:171","msg":"trace[1389977358] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1299; }","duration":"575.875038ms","start":"2026-05-07T03:28:31.446109Z","end":"2026-05-07T03:28:32.021984Z","steps":["trace[1389977358] 'agreement among raft nodes before linearized reading' (duration: 575.809327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:32.022035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:31.44609Z","time spent":"575.93411ms","remote":"127.0.0.1:34670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":26,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-07T03:28:32.023855Z","caller":"traceutil/trace.go:171","msg":"trace[342944597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1299; }","duration":"960.322481ms","start":"2026-05-07T03:28:31.060468Z","end":"2026-05-07T03:28:32.02079Z","steps":["trace[342944597] 'agreement among raft nodes before linearized reading' (duration: 960.085665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:32.023946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:31.060451Z","time spent":"963.454593ms","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:28:32.450216Z","caller":"traceutil/trace.go:171","msg":"trace[595131301] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"422.720116ms","start":"2026-05-07T03:28:32.027446Z","end":"2026-05-07T03:28:32.450166Z","steps":["trace[595131301] 'process raft request' (duration: 377.55702ms)","trace[595131301] 'compare' (duration: 44.969282ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:28:32.450397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:32.027421Z","time spent":"422.88433ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:28:32.495219Z","caller":"traceutil/trace.go:171","msg":"trace[1474447847] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"466.960111ms","start":"2026-05-07T03:28:32.028229Z","end":"2026-05-07T03:28:32.495189Z","steps":["trace[1474447847] 'process raft request' (duration: 466.741096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:32.495641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:32.028208Z","time spent":"467.231728ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:28:32.496032Z","caller":"traceutil/trace.go:171","msg":"trace[2063768510] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"466.511911ms","start":"2026-05-07T03:28:32.029485Z","end":"2026-05-07T03:28:32.495997Z","steps":["trace[2063768510] 'process raft request' (duration: 465.648921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:32.496111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:32.029465Z","time spent":"466.609013ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:28:32.496094Z","caller":"traceutil/trace.go:171","msg":"trace[223801534] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1352; }","duration":"334.141845ms","start":"2026-05-07T03:28:32.161921Z","end":"2026-05-07T03:28:32.496063Z","steps":["trace[223801534] 'read index received' (duration: 243.081275ms)","trace[223801534] 'applied index is now lower than readState.Index' (duration: 91.05901ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:28:32.496348Z","caller":"traceutil/trace.go:171","msg":"trace[1580360508] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"466.48336ms","start":"2026-05-07T03:28:32.029852Z","end":"2026-05-07T03:28:32.496335Z","steps":["trace[1580360508] 'process raft request' (duration: 465.918097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:32.496414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:32.029828Z","time spent":"466.552962ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:28:32.49658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.498994ms","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-07T03:28:32.496629Z","caller":"traceutil/trace.go:171","msg":"trace[781109346] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1303; }","duration":"334.742499ms","start":"2026-05-07T03:28:32.161874Z","end":"2026-05-07T03:28:32.496616Z","steps":["trace[781109346] 'agreement among raft nodes before linearized reading' (duration: 334.346189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:32.496718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:32.161853Z","time spent":"334.804721ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T03:28:33.591773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.615814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:28:33.591889Z","caller":"traceutil/trace.go:171","msg":"trace[241309023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1304; }","duration":"534.765397ms","start":"2026-05-07T03:28:33.057097Z","end":"2026-05-07T03:28:33.591863Z","steps":["trace[241309023] 'range keys from in-memory index tree' (duration: 534.473081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:33.591889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.747582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:28:33.591954Z","caller":"traceutil/trace.go:171","msg":"trace[155486086] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1304; }","duration":"810.889546ms","start":"2026-05-07T03:28:32.78105Z","end":"2026-05-07T03:28:33.591939Z","steps":["trace[155486086] 'count revisions from in-memory index tree' (duration: 810.63066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:33.591939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:33.057083Z","time spent":"534.84406ms","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:28:33.591998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:32.781019Z","time spent":"810.959718ms","remote":"127.0.0.1:44792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:28:33.592235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.399451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T03:28:33.592554Z","caller":"traceutil/trace.go:171","msg":"trace[1274620425] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:1304; }","duration":"930.79633ms","start":"2026-05-07T03:28:32.661731Z","end":"2026-05-07T03:28:33.592527Z","steps":["trace[1274620425] 'range keys from in-memory index tree' (duration: 930.101283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:33.592607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:32.6617Z","time spent":"930.894532ms","remote":"127.0.0.1:34400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"info","ts":"2026-05-07T03:28:34.299721Z","caller":"traceutil/trace.go:171","msg":"trace[1281765664] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"451.132211ms","start":"2026-05-07T03:28:33.848564Z","end":"2026-05-07T03:28:34.299696Z","steps":["trace[1281765664] 'process raft request' (duration: 435.935976ms)","trace[1281765664] 'compare' (duration: 15.057212ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:28:34.299858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:33.848513Z","time spent":"451.279075ms","remote":"127.0.0.1:34454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":783,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:28:34.327059Z","caller":"traceutil/trace.go:171","msg":"trace[362415963] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1359; }","duration":"267.567898ms","start":"2026-05-07T03:28:34.059467Z","end":"2026-05-07T03:28:34.327035Z","steps":["trace[362415963] 'read index received' (duration: 225.070124ms)","trace[362415963] 'applied index is now lower than readState.Index' (duration: 42.496884ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:28:34.32717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.68767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:28:34.327152Z","caller":"traceutil/trace.go:171","msg":"trace[1272785548] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"300.410906ms","start":"2026-05-07T03:28:34.026681Z","end":"2026-05-07T03:28:34.327092Z","steps":["trace[1272785548] 'process raft request' (duration: 300.240192ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:28:34.327198Z","caller":"traceutil/trace.go:171","msg":"trace[2030043521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1307; }","duration":"267.775532ms","start":"2026-05-07T03:28:34.059413Z","end":"2026-05-07T03:28:34.327189Z","steps":["trace[2030043521] 'agreement among raft nodes before linearized reading' (duration: 267.712381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:34.327322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:34.02665Z","time spent":"300.58554ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:28:36.756473Z","caller":"traceutil/trace.go:171","msg":"trace[1972897607] linearizableReadLoop","detail":"{readStateIndex:1369; appliedIndex:1368; }","duration":"289.202534ms","start":"2026-05-07T03:28:36.467249Z","end":"2026-05-07T03:28:36.756451Z","steps":["trace[1972897607] 'read index received' (duration: 289.096871ms)","trace[1972897607] 'applied index is now lower than readState.Index' (duration: 104.433µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:28:36.756613Z","caller":"traceutil/trace.go:171","msg":"trace[1223281133] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"424.709973ms","start":"2026-05-07T03:28:36.331857Z","end":"2026-05-07T03:28:36.756567Z","steps":["trace[1223281133] 'process raft request' (duration: 424.416046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:36.756949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:28:36.331834Z","time spent":"425.03424ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:28:36.757138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.286451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:28:36.757242Z","caller":"traceutil/trace.go:171","msg":"trace[2064507481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1315; }","duration":"243.449594ms","start":"2026-05-07T03:28:36.513778Z","end":"2026-05-07T03:28:36.757227Z","steps":["trace[2064507481] 'agreement among raft nodes before linearized reading' (duration: 243.194698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:36.757171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.375652ms","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-07T03:28:36.757368Z","caller":"traceutil/trace.go:171","msg":"trace[346298225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1315; }","duration":"243.560146ms","start":"2026-05-07T03:28:36.513772Z","end":"2026-05-07T03:28:36.757332Z","steps":["trace[346298225] 'agreement among raft nodes before linearized reading' (duration: 243.251039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:36.757453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.434511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-07T03:28:36.757666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.409012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T03:28:36.757727Z","caller":"traceutil/trace.go:171","msg":"trace[1079356644] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1315; }","duration":"290.504114ms","start":"2026-05-07T03:28:36.467211Z","end":"2026-05-07T03:28:36.757715Z","steps":["trace[1079356644] 'agreement among raft nodes before linearized reading' (duration: 289.607903ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:28:36.757611Z","caller":"traceutil/trace.go:171","msg":"trace[290671538] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1315; }","duration":"192.598644ms","start":"2026-05-07T03:28:36.564945Z","end":"2026-05-07T03:28:36.757544Z","steps":["trace[290671538] 'agreement among raft nodes before linearized reading' (duration: 192.097732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:36.758191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.82576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T03:28:36.758255Z","caller":"traceutil/trace.go:171","msg":"trace[1553949139] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1315; }","duration":"245.937402ms","start":"2026-05-07T03:28:36.512296Z","end":"2026-05-07T03:28:36.758233Z","steps":["trace[1553949139] 'agreement among raft nodes before linearized reading' (duration: 244.672283ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:28:51.060418Z","caller":"traceutil/trace.go:171","msg":"trace[1682812112] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"153.280675ms","start":"2026-05-07T03:28:50.907109Z","end":"2026-05-07T03:28:51.060389Z","steps":["trace[1682812112] 'process raft request' (duration: 153.06434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:51.060436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.824014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:28:51.060509Z","caller":"traceutil/trace.go:171","msg":"trace[1404557161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1421; }","duration":"152.962538ms","start":"2026-05-07T03:28:50.907532Z","end":"2026-05-07T03:28:51.060495Z","steps":["trace[1404557161] 'agreement among raft nodes before linearized reading' (duration: 152.785634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:28:51.060832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.05059ms","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-07T03:28:51.060952Z","caller":"traceutil/trace.go:171","msg":"trace[321739881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1421; }","duration":"153.213293ms","start":"2026-05-07T03:28:50.907713Z","end":"2026-05-07T03:28:51.060926Z","steps":["trace[321739881] 'agreement among raft nodes before linearized reading' (duration: 152.64338ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:29:15.37674Z","caller":"traceutil/trace.go:171","msg":"trace[1795474071] transaction","detail":"{read_only:false; response_revision:1546; number_of_response:1; }","duration":"100.280346ms","start":"2026-05-07T03:29:15.27643Z","end":"2026-05-07T03:29:15.37671Z","steps":["trace[1795474071] 'process raft request' (duration: 47.320187ms)","trace[1795474071] 'compare' (duration: 52.681452ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:29:35.572361Z","caller":"traceutil/trace.go:171","msg":"trace[10588812] transaction","detail":"{read_only:false; response_revision:1647; number_of_response:1; }","duration":"129.051103ms","start":"2026-05-07T03:29:35.44327Z","end":"2026-05-07T03:29:35.572321Z","steps":["trace[10588812] 'process raft request' (duration: 127.415655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:29:37.747915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.402723ms","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-07T03:29:37.748Z","caller":"traceutil/trace.go:171","msg":"trace[392879396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1654; }","duration":"150.542925ms","start":"2026-05-07T03:29:37.597439Z","end":"2026-05-07T03:29:37.747982Z","steps":["trace[392879396] 'range keys from in-memory index tree' (duration: 150.241529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:29:37.747999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.873602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T03:29:37.748215Z","caller":"traceutil/trace.go:171","msg":"trace[1977923199] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1654; }","duration":"171.107317ms","start":"2026-05-07T03:29:37.577032Z","end":"2026-05-07T03:29:37.748139Z","steps":["trace[1977923199] 'range keys from in-memory index tree' (duration: 170.609846ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:29:38.092107Z","caller":"traceutil/trace.go:171","msg":"trace[1751512662] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"339.479351ms","start":"2026-05-07T03:29:37.752597Z","end":"2026-05-07T03:29:38.092077Z","steps":["trace[1751512662] 'process raft request' (duration: 339.241955ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:29:38.092233Z","caller":"traceutil/trace.go:171","msg":"trace[1207143620] linearizableReadLoop","detail":"{readStateIndex:1723; appliedIndex:1721; }","duration":"297.540019ms","start":"2026-05-07T03:29:37.794663Z","end":"2026-05-07T03:29:38.092203Z","steps":["trace[1207143620] 'read index received' (duration: 297.208531ms)","trace[1207143620] 'applied index is now lower than readState.Index' (duration: 330.218µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:29:38.092305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:29:37.752574Z","time spent":"339.641064ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:29:38.092353Z","caller":"traceutil/trace.go:171","msg":"trace[1794174539] transaction","detail":"{read_only:false; response_revision:1657; number_of_response:1; }","duration":"339.631264ms","start":"2026-05-07T03:29:37.752704Z","end":"2026-05-07T03:29:38.092335Z","steps":["trace[1794174539] 'process raft request' (duration: 339.309627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:29:38.092439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.755164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-05-07T03:29:38.092483Z","caller":"traceutil/trace.go:171","msg":"trace[264166145] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1657; }","duration":"297.842246ms","start":"2026-05-07T03:29:37.794626Z","end":"2026-05-07T03:29:38.092468Z","steps":["trace[264166145] 'agreement among raft nodes before linearized reading' (duration: 297.677422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:29:38.092486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:29:37.752688Z","time spent":"339.713947ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:29:38.114473Z","caller":"traceutil/trace.go:171","msg":"trace[1121855226] transaction","detail":"{read_only:false; response_revision:1658; number_of_response:1; }","duration":"142.237621ms","start":"2026-05-07T03:29:37.972208Z","end":"2026-05-07T03:29:38.114445Z","steps":["trace[1121855226] 'process raft request' (duration: 142.005975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:29:38.26173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.140376ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-05-07T03:29:38.261832Z","caller":"traceutil/trace.go:171","msg":"trace[1172715225] linearizableReadLoop","detail":"{readStateIndex:1725; appliedIndex:1723; }","duration":"165.65481ms","start":"2026-05-07T03:29:38.096159Z","end":"2026-05-07T03:29:38.261814Z","steps":["trace[1172715225] 'read index received' (duration: 17.953341ms)","trace[1172715225] 'applied index is now lower than readState.Index' (duration: 147.699909ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:29:38.262075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.907476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-07T03:29:38.26217Z","caller":"traceutil/trace.go:171","msg":"trace[1456283829] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1658; }","duration":"166.041259ms","start":"2026-05-07T03:29:38.096111Z","end":"2026-05-07T03:29:38.262153Z","steps":["trace[1456283829] 'agreement among raft nodes before linearized reading' (duration: 165.745412ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:29:38.568224Z","caller":"traceutil/trace.go:171","msg":"trace[89660380] transaction","detail":"{read_only:false; response_revision:1660; number_of_response:1; }","duration":"220.468343ms","start":"2026-05-07T03:29:38.347728Z","end":"2026-05-07T03:29:38.568197Z","steps":["trace[89660380] 'process raft request' (duration: 211.486823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:29:42.822273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.887683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:29:42.822524Z","caller":"traceutil/trace.go:171","msg":"trace[2104806401] transaction","detail":"{read_only:false; response_revision:1682; number_of_response:1; }","duration":"149.139322ms","start":"2026-05-07T03:29:42.673324Z","end":"2026-05-07T03:29:42.822464Z","steps":["trace[2104806401] 'process raft request' (duration: 44.983343ms)","trace[2104806401] 'compare' (duration: 103.74424ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:29:59.130174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.009725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:29:59.130273Z","caller":"traceutil/trace.go:171","msg":"trace[571388810] linearizableReadLoop","detail":"{readStateIndex:1833; appliedIndex:1832; }","duration":"108.318257ms","start":"2026-05-07T03:29:59.02194Z","end":"2026-05-07T03:29:59.130258Z","steps":["trace[571388810] 'read index received' (duration: 72.912µs)","trace[571388810] 'applied index is now lower than readState.Index' (duration: 108.244135ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:29:59.130356Z","caller":"traceutil/trace.go:171","msg":"trace[1218808676] transaction","detail":"{read_only:false; response_revision:1762; number_of_response:1; }","duration":"196.752678ms","start":"2026-05-07T03:29:58.933594Z","end":"2026-05-07T03:29:59.130347Z","steps":["trace[1218808676] 'process raft request' (duration: 72.481798ms)","trace[1218808676] 'compare' (duration: 123.879831ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:29:59.130732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.757117ms","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-07T03:29:59.131116Z","caller":"traceutil/trace.go:171","msg":"trace[906025967] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1762; }","duration":"109.194187ms","start":"2026-05-07T03:29:59.021892Z","end":"2026-05-07T03:29:59.131086Z","steps":["trace[906025967] 'agreement among raft nodes before linearized reading' (duration: 108.560672ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:30:09.954792Z","caller":"traceutil/trace.go:171","msg":"trace[130396618] linearizableReadLoop","detail":"{readStateIndex:1892; appliedIndex:1891; }","duration":"202.619425ms","start":"2026-05-07T03:30:09.752144Z","end":"2026-05-07T03:30:09.954764Z","steps":["trace[130396618] 'read index received' (duration: 183.785124ms)","trace[130396618] 'applied index is now lower than readState.Index' (duration: 18.833251ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:30:09.955051Z","caller":"traceutil/trace.go:171","msg":"trace[755950188] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"221.940817ms","start":"2026-05-07T03:30:09.73308Z","end":"2026-05-07T03:30:09.955021Z","steps":["trace[755950188] 'process raft request' (duration: 202.851551ms)","trace[755950188] 'compare' (duration: 18.718858ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:30:09.955285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.999104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-07T03:30:09.955376Z","caller":"traceutil/trace.go:171","msg":"trace[609452344] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1819; }","duration":"203.242809ms","start":"2026-05-07T03:30:09.752109Z","end":"2026-05-07T03:30:09.955352Z","steps":["trace[609452344] 'agreement among raft nodes before linearized reading' (duration: 202.83487ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:30:10.172353Z","caller":"traceutil/trace.go:171","msg":"trace[948513422] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"213.289305ms","start":"2026-05-07T03:30:09.959045Z","end":"2026-05-07T03:30:10.172334Z","steps":["trace[948513422] 'process raft request' (duration: 203.324922ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:30:10.17703Z","caller":"traceutil/trace.go:171","msg":"trace[1937502373] linearizableReadLoop","detail":"{readStateIndex:1894; appliedIndex:1892; }","duration":"116.913589ms","start":"2026-05-07T03:30:10.0601Z","end":"2026-05-07T03:30:10.177014Z","steps":["trace[1937502373] 'read index received' (duration: 102.405279ms)","trace[1937502373] 'applied index is now lower than readState.Index' (duration: 14.50742ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:30:10.177178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.064632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:30:10.177147Z","caller":"traceutil/trace.go:171","msg":"trace[909047290] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"217.894863ms","start":"2026-05-07T03:30:09.959217Z","end":"2026-05-07T03:30:10.177112Z","steps":["trace[909047290] 'process raft request' (duration: 217.714379ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:30:10.177209Z","caller":"traceutil/trace.go:171","msg":"trace[1608028389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1821; }","duration":"117.143154ms","start":"2026-05-07T03:30:10.060058Z","end":"2026-05-07T03:30:10.177201Z","steps":["trace[1608028389] 'agreement among raft nodes before linearized reading' (duration: 117.024341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:30:10.62996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.584681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T03:30:10.630029Z","caller":"traceutil/trace.go:171","msg":"trace[2028416801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1821; }","duration":"356.687324ms","start":"2026-05-07T03:30:10.273325Z","end":"2026-05-07T03:30:10.630013Z","steps":["trace[2028416801] 'range keys from in-memory index tree' (duration: 356.451238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:30:10.630064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:30:10.273317Z","time spent":"356.737954ms","remote":"127.0.0.1:34652","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-07T03:30:10.630234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.872171ms","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-07T03:30:10.630298Z","caller":"traceutil/trace.go:171","msg":"trace[1514298045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1821; }","duration":"357.973704ms","start":"2026-05-07T03:30:10.272308Z","end":"2026-05-07T03:30:10.630281Z","steps":["trace[1514298045] 'range keys from in-memory index tree' (duration: 357.738248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:30:10.630335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:30:10.272292Z","time spent":"358.033205ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T03:31:25.430203Z","caller":"traceutil/trace.go:171","msg":"trace[51312965] linearizableReadLoop","detail":"{readStateIndex:2290; appliedIndex:2289; }","duration":"122.514986ms","start":"2026-05-07T03:31:25.307657Z","end":"2026-05-07T03:31:25.430172Z","steps":["trace[51312965] 'read index received' (duration: 122.314121ms)","trace[51312965] 'applied index is now lower than readState.Index' (duration: 199.625µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:31:25.430315Z","caller":"traceutil/trace.go:171","msg":"trace[1339987958] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"260.941953ms","start":"2026-05-07T03:31:25.169349Z","end":"2026-05-07T03:31:25.430291Z","steps":["trace[1339987958] 'process raft request' (duration: 260.652677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:31:25.430389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.70369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:31:25.430449Z","caller":"traceutil/trace.go:171","msg":"trace[1816993555] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2202; }","duration":"122.782892ms","start":"2026-05-07T03:31:25.30765Z","end":"2026-05-07T03:31:25.430433Z","steps":["trace[1816993555] 'agreement among raft nodes before linearized reading' (duration: 122.673659ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:31:26.201711Z","caller":"traceutil/trace.go:171","msg":"trace[1904120559] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"109.03167ms","start":"2026-05-07T03:31:26.092642Z","end":"2026-05-07T03:31:26.201674Z","steps":["trace[1904120559] 'process raft request' (duration: 108.813255ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:31:54.933334Z","caller":"traceutil/trace.go:171","msg":"trace[1333938281] transaction","detail":"{read_only:false; response_revision:2377; number_of_response:1; }","duration":"191.760881ms","start":"2026-05-07T03:31:54.74154Z","end":"2026-05-07T03:31:54.933301Z","steps":["trace[1333938281] 'process raft request' (duration: 191.520405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:31:58.221623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.331311ms","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-07T03:31:58.22176Z","caller":"traceutil/trace.go:171","msg":"trace[1629196850] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2392; }","duration":"115.507255ms","start":"2026-05-07T03:31:58.106233Z","end":"2026-05-07T03:31:58.22174Z","steps":["trace[1629196850] 'range keys from in-memory index tree' (duration: 115.314161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:31:58.602671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.881238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-07T03:31:58.602816Z","caller":"traceutil/trace.go:171","msg":"trace[1113784840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2393; }","duration":"129.083043ms","start":"2026-05-07T03:31:58.473695Z","end":"2026-05-07T03:31:58.602778Z","steps":["trace[1113784840] 'range keys from in-memory index tree' (duration: 128.55642ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:31:58.846173Z","caller":"traceutil/trace.go:171","msg":"trace[1096872456] linearizableReadLoop","detail":"{readStateIndex:2492; appliedIndex:2489; }","duration":"229.386701ms","start":"2026-05-07T03:31:58.616764Z","end":"2026-05-07T03:31:58.84615Z","steps":["trace[1096872456] 'read index received' (duration: 82.197025ms)","trace[1096872456] 'applied index is now lower than readState.Index' (duration: 147.188656ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:31:58.846255Z","caller":"traceutil/trace.go:171","msg":"trace[2026377225] transaction","detail":"{read_only:false; response_revision:2396; number_of_response:1; }","duration":"234.628595ms","start":"2026-05-07T03:31:58.611611Z","end":"2026-05-07T03:31:58.846239Z","steps":["trace[2026377225] 'process raft request' (duration: 234.456861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:31:58.846405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.840369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:31:58.846453Z","caller":"traceutil/trace.go:171","msg":"trace[1234818588] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2396; }","duration":"111.947771ms","start":"2026-05-07T03:31:58.734493Z","end":"2026-05-07T03:31:58.846441Z","steps":["trace[1234818588] 'agreement among raft nodes before linearized reading' (duration: 111.790628ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:31:58.846608Z","caller":"traceutil/trace.go:171","msg":"trace[237065789] transaction","detail":"{read_only:false; response_revision:2395; number_of_response:1; }","duration":"235.881724ms","start":"2026-05-07T03:31:58.610713Z","end":"2026-05-07T03:31:58.846594Z","steps":["trace[237065789] 'process raft request' (duration: 235.149277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:31:58.846588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.77171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-05-07T03:31:58.846707Z","caller":"traceutil/trace.go:171","msg":"trace[1701281617] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:2396; }","duration":"229.935484ms","start":"2026-05-07T03:31:58.616745Z","end":"2026-05-07T03:31:58.846681Z","steps":["trace[1701281617] 'agreement among raft nodes before linearized reading' (duration: 229.538925ms)"],"step_count":1}