{"level":"warn","ts":"2026-05-05T22:15:37.571486Z","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-05T22:15:37.571559Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248: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-05T22:15:37.571625Z","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-05T22:15:37.571637Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-05-05T22:15:37.571653Z","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-05T22:15:37.572248Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-05-05T22:15:37.572317Z","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.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248: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-05T22:15:37.577863Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.36517ms"} {"level":"info","ts":"2026-05-05T22:15:37.585892Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-05-05T22:15:37.585951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T22:15:37.585981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-05-05T22:15:37.585992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T22:15:37.586001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-05-05T22:15:37.586029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-05-05T22:15:37.599093Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T22:15:37.603822Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T22:15:37.606931Z","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-05T22:15:37.610124Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T22:15:37.610253Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T22:15:37.610427Z","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-05T22:15:37.610476Z","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-05T22:15:37.610487Z","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-05T22:15:37.611092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-05-05T22:15:37.611279Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-05-05T22:15:37.615346Z","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-05T22:15:37.615516Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T22:15:37.615545Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T22:15:37.61563Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-05-05T22:15:37.615635Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-05-05T22:15:38.486409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T22:15:38.486471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T22:15:38.486511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-05-05T22:15:38.48653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-05-05T22:15:38.486542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-05-05T22:15:38.486558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-05-05T22:15:38.486571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-05-05T22:15:39.002059Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:15:39.003828Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T22:15:39.003858Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T22:15:39.00395Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T22:15:39.004292Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:15:39.004309Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T22:15:39.004394Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T22:15:39.004417Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:15:39.00445Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:15:39.007274Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-05-05T22:15:39.008158Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T22:16:05.391167Z","caller":"traceutil/trace.go:171","msg":"trace[1117997830] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:440; }","duration":"160.074187ms","start":"2026-05-05T22:16:05.231065Z","end":"2026-05-05T22:16:05.391139Z","steps":["trace[1117997830] 'read index received' (duration: 159.920444ms)","trace[1117997830] 'applied index is now lower than readState.Index' (duration: 153.053µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:16:05.391244Z","caller":"traceutil/trace.go:171","msg":"trace[1549165152] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"672.185256ms","start":"2026-05-05T22:16:04.719021Z","end":"2026-05-05T22:16:05.391206Z","steps":["trace[1549165152] 'process raft request' (duration: 672.012422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:05.39128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.209761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:05.391329Z","caller":"traceutil/trace.go:171","msg":"trace[917721738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:427; }","duration":"160.316134ms","start":"2026-05-05T22:16:05.231004Z","end":"2026-05-05T22:16:05.39132Z","steps":["trace[917721738] 'agreement among raft nodes before linearized reading' (duration: 160.236362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:05.391831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:04.719Z","time spent":"672.31897ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:16:08.963754Z","caller":"traceutil/trace.go:171","msg":"trace[1587220927] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"444.326658ms","start":"2026-05-05T22:16:08.519403Z","end":"2026-05-05T22:16:08.963729Z","steps":["trace[1587220927] 'read index received' (duration: 444.191674ms)","trace[1587220927] 'applied index is now lower than readState.Index' (duration: 134.164µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:16:08.963896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.479781ms","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-05T22:16:08.963926Z","caller":"traceutil/trace.go:171","msg":"trace[383442046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:467; }","duration":"444.553653ms","start":"2026-05-05T22:16:08.519363Z","end":"2026-05-05T22:16:08.963917Z","steps":["trace[383442046] 'agreement among raft nodes before linearized reading' (duration: 444.466971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:08.963959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:08.519344Z","time spent":"444.608054ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T22:16:08.963983Z","caller":"traceutil/trace.go:171","msg":"trace[1769394075] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"505.681932ms","start":"2026-05-05T22:16:08.458272Z","end":"2026-05-05T22:16:08.963954Z","steps":["trace[1769394075] 'process raft request' (duration: 505.337064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:08.964117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:08.458189Z","time spent":"505.855016ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:16:09.525838Z","caller":"traceutil/trace.go:171","msg":"trace[1452404506] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"509.782063ms","start":"2026-05-05T22:16:09.016031Z","end":"2026-05-05T22:16:09.525813Z","steps":["trace[1452404506] 'read index received' (duration: 443.086309ms)","trace[1452404506] 'applied index is now lower than readState.Index' (duration: 66.694734ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:16:09.52592Z","caller":"traceutil/trace.go:171","msg":"trace[1366838571] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"557.72595ms","start":"2026-05-05T22:16:08.968162Z","end":"2026-05-05T22:16:09.525888Z","steps":["trace[1366838571] 'process raft request' (duration: 490.914113ms)","trace[1366838571] 'compare' (duration: 66.629032ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:16:09.525986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.937957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:09.526026Z","caller":"traceutil/trace.go:171","msg":"trace[1492450280] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:468; }","duration":"510.022779ms","start":"2026-05-05T22:16:09.015992Z","end":"2026-05-05T22:16:09.526014Z","steps":["trace[1492450280] 'agreement among raft nodes before linearized reading' (duration: 509.945827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:09.526075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:09.015974Z","time spent":"510.08779ms","remote":"127.0.0.1:44012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-05T22:16:09.526072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.341446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:16:09.52609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:08.96814Z","time spent":"557.866304ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:16:09.526143Z","caller":"traceutil/trace.go:171","msg":"trace[1425690821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:468; }","duration":"296.438868ms","start":"2026-05-05T22:16:09.229682Z","end":"2026-05-05T22:16:09.526121Z","steps":["trace[1425690821] 'agreement among raft nodes before linearized reading' (duration: 296.325515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:09.52614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.174261ms","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-05T22:16:09.526385Z","caller":"traceutil/trace.go:171","msg":"trace[192288020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:468; }","duration":"121.428817ms","start":"2026-05-05T22:16:09.404923Z","end":"2026-05-05T22:16:09.526351Z","steps":["trace[192288020] 'agreement among raft nodes before linearized reading' (duration: 121.093139ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:10.47492Z","caller":"traceutil/trace.go:171","msg":"trace[896523997] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"170.667655ms","start":"2026-05-05T22:16:10.30423Z","end":"2026-05-05T22:16:10.474898Z","steps":["trace[896523997] 'process raft request' (duration: 170.44209ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:10.480378Z","caller":"traceutil/trace.go:171","msg":"trace[16175335] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"175.714448ms","start":"2026-05-05T22:16:10.304633Z","end":"2026-05-05T22:16:10.480347Z","steps":["trace[16175335] 'process raft request' (duration: 175.294118ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:10.480374Z","caller":"traceutil/trace.go:171","msg":"trace[912058650] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"154.523843ms","start":"2026-05-05T22:16:10.325806Z","end":"2026-05-05T22:16:10.48033Z","steps":["trace[912058650] 'process raft request' (duration: 154.343058ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:15.910175Z","caller":"traceutil/trace.go:171","msg":"trace[1643166821] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"365.578771ms","start":"2026-05-05T22:16:15.544568Z","end":"2026-05-05T22:16:15.910147Z","steps":["trace[1643166821] 'read index received' (duration: 364.765191ms)","trace[1643166821] 'applied index is now lower than readState.Index' (duration: 812.479µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:16:15.910235Z","caller":"traceutil/trace.go:171","msg":"trace[1790260192] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"512.827155ms","start":"2026-05-05T22:16:15.397385Z","end":"2026-05-05T22:16:15.910212Z","steps":["trace[1790260192] 'process raft request' (duration: 512.21588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:15.910368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:15.397355Z","time spent":"512.933258ms","remote":"127.0.0.1:44012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036249,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:16:15.910381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.811977ms","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-05T22:16:15.910507Z","caller":"traceutil/trace.go:171","msg":"trace[1000599316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"365.95608ms","start":"2026-05-05T22:16:15.544511Z","end":"2026-05-05T22:16:15.910467Z","steps":["trace[1000599316] 'agreement among raft nodes before linearized reading' (duration: 365.766886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:15.910637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:15.544487Z","time spent":"366.109283ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T22:16:15.910634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.905598ms","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-05T22:16:15.91074Z","caller":"traceutil/trace.go:171","msg":"trace[1395638008] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"352.065511ms","start":"2026-05-05T22:16:15.558655Z","end":"2026-05-05T22:16:15.910721Z","steps":["trace[1395638008] 'agreement among raft nodes before linearized reading' (duration: 351.737053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:15.910796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:15.558634Z","time spent":"352.153734ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T22:16:16.132296Z","caller":"traceutil/trace.go:171","msg":"trace[177598783] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"217.61148ms","start":"2026-05-05T22:16:15.914657Z","end":"2026-05-05T22:16:16.132268Z","steps":["trace[177598783] 'process raft request' (duration: 217.463927ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:16.149945Z","caller":"traceutil/trace.go:171","msg":"trace[1937007694] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"234.741236ms","start":"2026-05-05T22:16:15.915171Z","end":"2026-05-05T22:16:16.149912Z","steps":["trace[1937007694] 'process raft request' (duration: 234.561972ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:16.150013Z","caller":"traceutil/trace.go:171","msg":"trace[1626522372] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"186.541262ms","start":"2026-05-05T22:16:15.963458Z","end":"2026-05-05T22:16:16.149999Z","steps":["trace[1626522372] 'process raft request' (duration: 186.341958ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:16.150035Z","caller":"traceutil/trace.go:171","msg":"trace[1398880978] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:594; }","duration":"216.977864ms","start":"2026-05-05T22:16:15.933013Z","end":"2026-05-05T22:16:16.149991Z","steps":["trace[1398880978] 'read index received' (duration: 199.034468ms)","trace[1398880978] 'applied index is now lower than readState.Index' (duration: 17.941606ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:16:16.150174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.150068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x4rr8\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-05T22:16:16.15021Z","caller":"traceutil/trace.go:171","msg":"trace[474531871] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x4rr8; range_end:; response_count:1; response_revision:576; }","duration":"217.218899ms","start":"2026-05-05T22:16:15.932982Z","end":"2026-05-05T22:16:16.150201Z","steps":["trace[474531871] 'agreement among raft nodes before linearized reading' (duration: 217.079976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:16.150354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.372703ms","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-05T22:16:16.15042Z","caller":"traceutil/trace.go:171","msg":"trace[1568061425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"187.464945ms","start":"2026-05-05T22:16:15.962943Z","end":"2026-05-05T22:16:16.150408Z","steps":["trace[1568061425] 'agreement among raft nodes before linearized reading' (duration: 187.263221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:16.820328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.820611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:16:16.820481Z","caller":"traceutil/trace.go:171","msg":"trace[565770618] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"666.581572ms","start":"2026-05-05T22:16:16.153858Z","end":"2026-05-05T22:16:16.82044Z","steps":["trace[565770618] 'process raft request' (duration: 465.391232ms)","trace[565770618] 'compare' (duration: 200.306508ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:16:16.820537Z","caller":"traceutil/trace.go:171","msg":"trace[880707577] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"660.70635ms","start":"2026-05-05T22:16:16.159806Z","end":"2026-05-05T22:16:16.820512Z","steps":["trace[880707577] 'read index received' (duration: 459.455759ms)","trace[880707577] 'applied index is now lower than readState.Index' (duration: 201.248021ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:16:16.820638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:16.153842Z","time spent":"666.716316ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:16:16.82076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.947085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:16:16.820769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.729525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:16.820808Z","caller":"traceutil/trace.go:171","msg":"trace[228741211] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:578; }","duration":"661.026708ms","start":"2026-05-05T22:16:16.159769Z","end":"2026-05-05T22:16:16.820796Z","steps":["trace[228741211] 'agreement among raft nodes before linearized reading' (duration: 660.866154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:16.82084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:16.159756Z","time spent":"661.076609ms","remote":"127.0.0.1:33010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"info","ts":"2026-05-05T22:16:16.820842Z","caller":"traceutil/trace.go:171","msg":"trace[642437099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"590.851028ms","start":"2026-05-05T22:16:16.229971Z","end":"2026-05-05T22:16:16.820822Z","steps":["trace[642437099] 'agreement among raft nodes before linearized reading' (duration: 590.710935ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:16.821033Z","caller":"traceutil/trace.go:171","msg":"trace[2022478817] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"655.38159ms","start":"2026-05-05T22:16:16.165598Z","end":"2026-05-05T22:16:16.820979Z","steps":["trace[2022478817] 'process raft request' (duration: 654.860387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:16.821279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:16.165564Z","time spent":"655.584805ms","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:16:16.822256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:16.229948Z","time spent":"592.248482ms","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T22:16:18.381524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.374601552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T22:16:18.3816Z","caller":"traceutil/trace.go:171","msg":"trace[330626001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"1.374726725s","start":"2026-05-05T22:16:17.006857Z","end":"2026-05-05T22:16:18.381584Z","steps":["trace[330626001] 'range keys from in-memory index tree' (duration: 1.374515211s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:18.381639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:17.006835Z","time spent":"1.374795768s","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T22:16:18.44061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.539905ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T22:16:18.442533Z","caller":"traceutil/trace.go:171","msg":"trace[2093389330] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"304.457393ms","start":"2026-05-05T22:16:18.138056Z","end":"2026-05-05T22:16:18.442513Z","steps":["trace[2093389330] 'read index received' (duration: 303.719515ms)","trace[2093389330] 'applied index is now lower than readState.Index' (duration: 736.978µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:16:18.442604Z","caller":"traceutil/trace.go:171","msg":"trace[566457735] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"1.231746955s","start":"2026-05-05T22:16:17.210831Z","end":"2026-05-05T22:16:18.442578Z","steps":["trace[566457735] 'process raft request' (duration: 1.231095009s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:18.44324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.176731ms","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-05T22:16:18.443387Z","caller":"traceutil/trace.go:171","msg":"trace[1560870613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"305.320745ms","start":"2026-05-05T22:16:18.137992Z","end":"2026-05-05T22:16:18.443312Z","steps":["trace[1560870613] 'agreement among raft nodes before linearized reading' (duration: 304.608367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:18.443475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:18.137966Z","time spent":"305.495518ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T22:16:18.449648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:17.210808Z","time spent":"1.231884928s","remote":"127.0.0.1:44012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:16:18.459671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.840792ms","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-05T22:16:18.459744Z","caller":"traceutil/trace.go:171","msg":"trace[1879261346] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"304.965345ms","start":"2026-05-05T22:16:18.154762Z","end":"2026-05-05T22:16:18.459727Z","steps":["trace[1879261346] 'agreement among raft nodes before linearized reading' (duration: 304.790361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:18.459776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:18.154741Z","time spent":"305.027577ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T22:16:18.459829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.112036ms","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":"warn","ts":"2026-05-05T22:16:18.459958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.284221ms","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-05T22:16:18.459992Z","caller":"traceutil/trace.go:171","msg":"trace[1173879222] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:580; }","duration":"171.330452ms","start":"2026-05-05T22:16:18.288652Z","end":"2026-05-05T22:16:18.459982Z","steps":["trace[1173879222] 'agreement among raft nodes before linearized reading' (duration: 171.28066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:18.460076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.099053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:18.460119Z","caller":"traceutil/trace.go:171","msg":"trace[283806975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"230.176515ms","start":"2026-05-05T22:16:18.229931Z","end":"2026-05-05T22:16:18.460107Z","steps":["trace[283806975] 'agreement among raft nodes before linearized reading' (duration: 230.110923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:18.460127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.853722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:18.460152Z","caller":"traceutil/trace.go:171","msg":"trace[1620387996] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:580; }","duration":"290.904494ms","start":"2026-05-05T22:16:18.169238Z","end":"2026-05-05T22:16:18.460142Z","steps":["trace[1620387996] 'agreement among raft nodes before linearized reading' (duration: 290.865633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:18.460272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.469217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:16:18.460287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.961128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:18.4603Z","caller":"traceutil/trace.go:171","msg":"trace[1422702984] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:580; }","duration":"291.523708ms","start":"2026-05-05T22:16:18.16877Z","end":"2026-05-05T22:16:18.460294Z","steps":["trace[1422702984] 'agreement among raft nodes before linearized reading' (duration: 291.481697ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:18.460311Z","caller":"traceutil/trace.go:171","msg":"trace[2005758959] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:580; }","duration":"201.993968ms","start":"2026-05-05T22:16:18.258309Z","end":"2026-05-05T22:16:18.460303Z","steps":["trace[2005758959] 'agreement among raft nodes before linearized reading' (duration: 201.956268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:18.460393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.639012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:18.46042Z","caller":"traceutil/trace.go:171","msg":"trace[1220473200] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:580; }","duration":"291.699462ms","start":"2026-05-05T22:16:18.168715Z","end":"2026-05-05T22:16:18.460414Z","steps":["trace[1220473200] 'agreement among raft nodes before linearized reading' (duration: 291.657831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:18.460479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.690276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:18.460508Z","caller":"traceutil/trace.go:171","msg":"trace[417164646] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:580; }","duration":"202.765447ms","start":"2026-05-05T22:16:18.257735Z","end":"2026-05-05T22:16:18.460501Z","steps":["trace[417164646] 'agreement among raft nodes before linearized reading' (duration: 202.700116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:18.460626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.88776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:18.460654Z","caller":"traceutil/trace.go:171","msg":"trace[118720604] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:580; }","duration":"202.930472ms","start":"2026-05-05T22:16:18.257717Z","end":"2026-05-05T22:16:18.460648Z","steps":["trace[118720604] 'agreement among raft nodes before linearized reading' (duration: 202.889181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:18.460781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.270721ms","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-05T22:16:18.46081Z","caller":"traceutil/trace.go:171","msg":"trace[661973574] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:580; }","duration":"139.337153ms","start":"2026-05-05T22:16:18.321467Z","end":"2026-05-05T22:16:18.460804Z","steps":["trace[661973574] 'agreement among raft nodes before linearized reading' (duration: 139.291912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:18.460919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.436875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:18.460947Z","caller":"traceutil/trace.go:171","msg":"trace[1261642332] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:580; }","duration":"116.489077ms","start":"2026-05-05T22:16:18.344451Z","end":"2026-05-05T22:16:18.46094Z","steps":["trace[1261642332] 'agreement among raft nodes before linearized reading' (duration: 116.448146ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:18.460962Z","caller":"traceutil/trace.go:171","msg":"trace[764164274] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:580; }","duration":"171.194369ms","start":"2026-05-05T22:16:18.288677Z","end":"2026-05-05T22:16:18.459871Z","steps":["trace[764164274] 'agreement among raft nodes before linearized reading' (duration: 171.035985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:18.46135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.906337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:16:18.461353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.388644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:18.461387Z","caller":"traceutil/trace.go:171","msg":"trace[501198885] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:580; }","duration":"139.955158ms","start":"2026-05-05T22:16:18.321421Z","end":"2026-05-05T22:16:18.461376Z","steps":["trace[501198885] 'agreement among raft nodes before linearized reading' (duration: 139.901886ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:18.461389Z","caller":"traceutil/trace.go:171","msg":"trace[546040298] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:580; }","duration":"116.428585ms","start":"2026-05-05T22:16:18.344953Z","end":"2026-05-05T22:16:18.461381Z","steps":["trace[546040298] 'agreement among raft nodes before linearized reading' (duration: 116.375534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:18.461353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.937947ms","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-05T22:16:18.462065Z","caller":"traceutil/trace.go:171","msg":"trace[1010863448] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:580; }","duration":"140.667565ms","start":"2026-05-05T22:16:18.321386Z","end":"2026-05-05T22:16:18.462053Z","steps":["trace[1010863448] 'agreement among raft nodes before linearized reading' (duration: 139.945448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:18.462338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.763928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:18.464096Z","caller":"traceutil/trace.go:171","msg":"trace[844355819] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:580; }","duration":"119.52912ms","start":"2026-05-05T22:16:18.344552Z","end":"2026-05-05T22:16:18.464081Z","steps":["trace[844355819] 'agreement among raft nodes before linearized reading' (duration: 117.753407ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:24.11475Z","caller":"traceutil/trace.go:171","msg":"trace[1286262304] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"107.928228ms","start":"2026-05-05T22:16:24.00678Z","end":"2026-05-05T22:16:24.114708Z","steps":["trace[1286262304] 'process raft request' (duration: 105.999051ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:32.14626Z","caller":"traceutil/trace.go:171","msg":"trace[1465328772] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"108.867552ms","start":"2026-05-05T22:16:32.03737Z","end":"2026-05-05T22:16:32.146238Z","steps":["trace[1465328772] 'process raft request' (duration: 108.8002ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:32.146325Z","caller":"traceutil/trace.go:171","msg":"trace[1147045934] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"192.949519ms","start":"2026-05-05T22:16:31.953328Z","end":"2026-05-05T22:16:32.146278Z","steps":["trace[1147045934] 'process raft request' (duration: 98.264723ms)","trace[1147045934] 'compare' (duration: 94.44996ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:16:36.743798Z","caller":"traceutil/trace.go:171","msg":"trace[1165318984] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"107.063727ms","start":"2026-05-05T22:16:36.636713Z","end":"2026-05-05T22:16:36.743777Z","steps":["trace[1165318984] 'process raft request' (duration: 106.566346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:40.326183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.546549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xdm8j\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-05-05T22:16:40.326268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.004647ms","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-05T22:16:40.32636Z","caller":"traceutil/trace.go:171","msg":"trace[719956489] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:760; }","duration":"145.14599ms","start":"2026-05-05T22:16:40.181186Z","end":"2026-05-05T22:16:40.326332Z","steps":["trace[719956489] 'range keys from in-memory index tree' (duration: 144.781311ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:40.326361Z","caller":"traceutil/trace.go:171","msg":"trace[1500405204] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xdm8j; range_end:; response_count:1; response_revision:760; }","duration":"269.249935ms","start":"2026-05-05T22:16:40.057066Z","end":"2026-05-05T22:16:40.326316Z","steps":["trace[1500405204] 'range keys from in-memory index tree' (duration: 268.381985ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:40.805172Z","caller":"traceutil/trace.go:171","msg":"trace[861516814] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"474.313631ms","start":"2026-05-05T22:16:40.330834Z","end":"2026-05-05T22:16:40.805148Z","steps":["trace[861516814] 'process raft request' (duration: 474.024064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:40.806729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:40.330814Z","time spent":"474.441964ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:16:40.843807Z","caller":"traceutil/trace.go:171","msg":"trace[615123460] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:787; }","duration":"198.645303ms","start":"2026-05-05T22:16:40.645135Z","end":"2026-05-05T22:16:40.843781Z","steps":["trace[615123460] 'read index received' (duration: 159.782531ms)","trace[615123460] 'applied index is now lower than readState.Index' (duration: 38.861552ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:16:40.843942Z","caller":"traceutil/trace.go:171","msg":"trace[986169448] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"510.844537ms","start":"2026-05-05T22:16:40.333031Z","end":"2026-05-05T22:16:40.843876Z","steps":["trace[986169448] 'process raft request' (duration: 510.577481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:40.844055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.911989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T22:16:40.844096Z","caller":"traceutil/trace.go:171","msg":"trace[908580409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:762; }","duration":"199.040702ms","start":"2026-05-05T22:16:40.645043Z","end":"2026-05-05T22:16:40.844084Z","steps":["trace[908580409] 'agreement among raft nodes before linearized reading' (duration: 198.866078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:40.844125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:40.33301Z","time spent":"511.013481ms","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:16:40.844182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.926515ms","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-05T22:16:40.844268Z","caller":"traceutil/trace.go:171","msg":"trace[1250469071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:762; }","duration":"150.069718ms","start":"2026-05-05T22:16:40.694176Z","end":"2026-05-05T22:16:40.844246Z","steps":["trace[1250469071] 'agreement among raft nodes before linearized reading' (duration: 149.814942ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:44.665074Z","caller":"traceutil/trace.go:171","msg":"trace[1805094177] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"104.028034ms","start":"2026-05-05T22:16:44.561023Z","end":"2026-05-05T22:16:44.665051Z","steps":["trace[1805094177] 'process raft request' (duration: 102.452476ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:44.828443Z","caller":"traceutil/trace.go:171","msg":"trace[1657349353] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"198.577101ms","start":"2026-05-05T22:16:44.629844Z","end":"2026-05-05T22:16:44.828421Z","steps":["trace[1657349353] 'process raft request' (duration: 106.041392ms)","trace[1657349353] 'compare' (duration: 92.299913ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:16:45.298825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.457486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:16:45.299468Z","caller":"traceutil/trace.go:171","msg":"trace[946172257] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"437.15595ms","start":"2026-05-05T22:16:44.862286Z","end":"2026-05-05T22:16:45.299442Z","steps":["trace[946172257] 'read index received' (duration: 4.272032ms)","trace[946172257] 'applied index is now lower than readState.Index' (duration: 432.881708ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:16:45.299614Z","caller":"traceutil/trace.go:171","msg":"trace[1786322560] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"463.523733ms","start":"2026-05-05T22:16:44.835993Z","end":"2026-05-05T22:16:45.299516Z","steps":["trace[1786322560] 'process raft request' (duration: 101.309289ms)","trace[1786322560] 'compare' (duration: 361.22484ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:16:45.299729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.426168ms","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-05T22:16:45.299783Z","caller":"traceutil/trace.go:171","msg":"trace[1742887649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:809; }","duration":"437.509539ms","start":"2026-05-05T22:16:44.862262Z","end":"2026-05-05T22:16:45.299772Z","steps":["trace[1742887649] 'agreement among raft nodes before linearized reading' (duration: 437.301994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:45.299822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:44.862253Z","time spent":"437.56117ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T22:16:45.299934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:44.835973Z","time spent":"463.728327ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2784,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T22:16:45.299942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.415184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:45.300072Z","caller":"traceutil/trace.go:171","msg":"trace[1581447016] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:809; }","duration":"396.605069ms","start":"2026-05-05T22:16:44.903448Z","end":"2026-05-05T22:16:45.300053Z","steps":["trace[1581447016] 'agreement among raft nodes before linearized reading' (duration: 396.424345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:45.300127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.503077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-05T22:16:45.300141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:44.903422Z","time spent":"396.705631ms","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-05-05T22:16:45.300209Z","caller":"traceutil/trace.go:171","msg":"trace[577144757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:809; }","duration":"434.566229ms","start":"2026-05-05T22:16:44.865607Z","end":"2026-05-05T22:16:45.300173Z","steps":["trace[577144757] 'agreement among raft nodes before linearized reading' (duration: 434.429886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:45.300217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.598979ms","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-05T22:16:45.300262Z","caller":"traceutil/trace.go:171","msg":"trace[2006845336] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:809; }","duration":"137.64339ms","start":"2026-05-05T22:16:45.162608Z","end":"2026-05-05T22:16:45.300252Z","steps":["trace[2006845336] 'agreement among raft nodes before linearized reading' (duration: 137.537877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:45.300271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:44.865603Z","time spent":"434.656431ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T22:16:45.299978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.927557ms","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-05T22:16:45.300436Z","caller":"traceutil/trace.go:171","msg":"trace[1880090631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:809; }","duration":"435.423239ms","start":"2026-05-05T22:16:44.864999Z","end":"2026-05-05T22:16:45.300423Z","steps":["trace[1880090631] 'agreement among raft nodes before linearized reading' (duration: 434.740502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:45.30048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:44.864995Z","time spent":"435.474941ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T22:16:45.300692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.877086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050260"} {"level":"info","ts":"2026-05-05T22:16:45.300773Z","caller":"traceutil/trace.go:171","msg":"trace[2007587346] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:809; }","duration":"279.007079ms","start":"2026-05-05T22:16:45.021747Z","end":"2026-05-05T22:16:45.300754Z","steps":["trace[2007587346] 'agreement among raft nodes before linearized reading' (duration: 278.039316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:07.431418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.794484ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T22:17:07.431527Z","caller":"traceutil/trace.go:171","msg":"trace[551150652] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"201.360857ms","start":"2026-05-05T22:17:07.230148Z","end":"2026-05-05T22:17:07.431509Z","steps":["trace[551150652] 'read index received' (duration: 53.434871ms)","trace[551150652] 'applied index is now lower than readState.Index' (duration: 147.924506ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:07.431601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.439599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:07.431627Z","caller":"traceutil/trace.go:171","msg":"trace[1807387700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:942; }","duration":"201.47528ms","start":"2026-05-05T22:17:07.230143Z","end":"2026-05-05T22:17:07.431618Z","steps":["trace[1807387700] 'agreement among raft nodes before linearized reading' (duration: 201.412628ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:15.475446Z","caller":"traceutil/trace.go:171","msg":"trace[2076968717] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"245.080456ms","start":"2026-05-05T22:17:15.230343Z","end":"2026-05-05T22:17:15.475423Z","steps":["trace[2076968717] 'read index received' (duration: 244.958963ms)","trace[2076968717] 'applied index is now lower than readState.Index' (duration: 120.123µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:15.475479Z","caller":"traceutil/trace.go:171","msg":"trace[1474484825] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"379.584161ms","start":"2026-05-05T22:17:15.095873Z","end":"2026-05-05T22:17:15.475457Z","steps":["trace[1474484825] 'process raft request' (duration: 379.423247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:15.475544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.189349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:15.475569Z","caller":"traceutil/trace.go:171","msg":"trace[478747871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"245.256071ms","start":"2026-05-05T22:17:15.230305Z","end":"2026-05-05T22:17:15.475561Z","steps":["trace[478747871] 'agreement among raft nodes before linearized reading' (duration: 245.198879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:15.475597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:15.095855Z","time spent":"379.682513ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:15.824241Z","caller":"traceutil/trace.go:171","msg":"trace[1705289619] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"189.092814ms","start":"2026-05-05T22:17:15.635116Z","end":"2026-05-05T22:17:15.824208Z","steps":["trace[1705289619] 'read index received' (duration: 188.905639ms)","trace[1705289619] 'applied index is now lower than readState.Index' (duration: 185.594µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:15.824332Z","caller":"traceutil/trace.go:171","msg":"trace[1586892252] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"194.279068ms","start":"2026-05-05T22:17:15.630016Z","end":"2026-05-05T22:17:15.824295Z","steps":["trace[1586892252] 'process raft request' (duration: 193.987231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:15.824559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.428312ms","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-05T22:17:15.824604Z","caller":"traceutil/trace.go:171","msg":"trace[207375090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:973; }","duration":"189.559965ms","start":"2026-05-05T22:17:15.635032Z","end":"2026-05-05T22:17:15.824592Z","steps":["trace[207375090] 'agreement among raft nodes before linearized reading' (duration: 189.37759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:15.824768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.637364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T22:17:15.824808Z","caller":"traceutil/trace.go:171","msg":"trace[517150217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:973; }","duration":"143.747437ms","start":"2026-05-05T22:17:15.681051Z","end":"2026-05-05T22:17:15.824798Z","steps":["trace[517150217] 'agreement among raft nodes before linearized reading' (duration: 143.509991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:15.824832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.440119ms","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-05T22:17:15.825049Z","caller":"traceutil/trace.go:171","msg":"trace[1173835917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:973; }","duration":"143.632514ms","start":"2026-05-05T22:17:15.681269Z","end":"2026-05-05T22:17:15.824901Z","steps":["trace[1173835917] 'agreement among raft nodes before linearized reading' (duration: 143.374878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:16.620296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.408375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:17:16.620473Z","caller":"traceutil/trace.go:171","msg":"trace[35772087] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"790.01843ms","start":"2026-05-05T22:17:15.830437Z","end":"2026-05-05T22:17:16.620455Z","steps":["trace[35772087] 'process raft request' (duration: 789.961139ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:16.620513Z","caller":"traceutil/trace.go:171","msg":"trace[1612054342] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"791.028225ms","start":"2026-05-05T22:17:15.829455Z","end":"2026-05-05T22:17:16.620483Z","steps":["trace[1612054342] 'process raft request' (duration: 59.344433ms)","trace[1612054342] 'compare' (duration: 731.283583ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:16.620544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:15.830425Z","time spent":"790.080942ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:17:16.620661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:15.829421Z","time spent":"791.160828ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:16.628173Z","caller":"traceutil/trace.go:171","msg":"trace[716073735] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"748.792133ms","start":"2026-05-05T22:17:15.879357Z","end":"2026-05-05T22:17:16.628149Z","steps":["trace[716073735] 'process raft request' (duration: 748.66783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:16.628515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:15.879326Z","time spent":"749.12932ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:17:18.013629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.273719597s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:17:18.014354Z","caller":"traceutil/trace.go:171","msg":"trace[1248010116] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"1.990139772s","start":"2026-05-05T22:17:16.024166Z","end":"2026-05-05T22:17:18.014306Z","steps":["trace[1248010116] 'process raft request' (duration: 715.674317ms)","trace[1248010116] 'compare' (duration: 1.273364239s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:18.014547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:16.024144Z","time spent":"1.990325217s","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:17:18.463756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.02492ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T22:17:18.463834Z","caller":"traceutil/trace.go:171","msg":"trace[347996535] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1013; }","duration":"1.234887957s","start":"2026-05-05T22:17:17.228932Z","end":"2026-05-05T22:17:18.46382Z","steps":["trace[347996535] 'read index received' (duration: 241.477889ms)","trace[347996535] 'applied index is now lower than readState.Index' (duration: 993.409018ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:18.464087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.235148413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:18.464108Z","caller":"traceutil/trace.go:171","msg":"trace[282099927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"1.235199674s","start":"2026-05-05T22:17:17.228901Z","end":"2026-05-05T22:17:18.464101Z","steps":["trace[282099927] 'agreement among raft nodes before linearized reading' (duration: 1.235143903s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:18.46413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:17.228885Z","time spent":"1.235241335s","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T22:17:18.464211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.228957234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T22:17:18.464267Z","caller":"traceutil/trace.go:171","msg":"trace[1378990538] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:978; }","duration":"1.229044337s","start":"2026-05-05T22:17:17.235209Z","end":"2026-05-05T22:17:18.464254Z","steps":["trace[1378990538] 'agreement among raft nodes before linearized reading' (duration: 1.228931874s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:18.464259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.009677ms","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-05T22:17:18.464296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:17.235202Z","time spent":"1.229088098s","remote":"127.0.0.1:44292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-05-05T22:17:18.464319Z","caller":"traceutil/trace.go:171","msg":"trace[597217087] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:978; }","duration":"613.15602ms","start":"2026-05-05T22:17:17.851148Z","end":"2026-05-05T22:17:18.464304Z","steps":["trace[597217087] 'agreement among raft nodes before linearized reading' (duration: 613.005227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:18.464303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.80189ms","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-05T22:17:18.464348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:17.851099Z","time spent":"613.243593ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T22:17:18.464373Z","caller":"traceutil/trace.go:171","msg":"trace[1659332357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:978; }","duration":"634.914472ms","start":"2026-05-05T22:17:17.829442Z","end":"2026-05-05T22:17:18.464356Z","steps":["trace[1659332357] 'agreement among raft nodes before linearized reading' (duration: 634.697217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:18.464411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:17.829421Z","time spent":"634.982254ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T22:17:18.464689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.50222ms","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-05T22:17:18.464714Z","caller":"traceutil/trace.go:171","msg":"trace[746518395] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:978; }","duration":"983.559551ms","start":"2026-05-05T22:17:17.481147Z","end":"2026-05-05T22:17:18.464707Z","steps":["trace[746518395] 'agreement among raft nodes before linearized reading' (duration: 983.461849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:18.464733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:17.481124Z","time spent":"983.605202ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T22:17:20.636449Z","caller":"traceutil/trace.go:171","msg":"trace[1045601856] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"147.999928ms","start":"2026-05-05T22:17:20.48843Z","end":"2026-05-05T22:17:20.63643Z","steps":["trace[1045601856] 'process raft request' (duration: 147.869085ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:20.638759Z","caller":"traceutil/trace.go:171","msg":"trace[1621186676] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"148.662124ms","start":"2026-05-05T22:17:20.490073Z","end":"2026-05-05T22:17:20.638735Z","steps":["trace[1621186676] 'process raft request' (duration: 148.618963ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:20.638822Z","caller":"traceutil/trace.go:171","msg":"trace[1729011076] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"149.937564ms","start":"2026-05-05T22:17:20.488855Z","end":"2026-05-05T22:17:20.638792Z","steps":["trace[1729011076] 'process raft request' (duration: 149.716619ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:22.075806Z","caller":"traceutil/trace.go:171","msg":"trace[1127030774] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"117.443746ms","start":"2026-05-05T22:17:21.958335Z","end":"2026-05-05T22:17:22.075779Z","steps":["trace[1127030774] 'process raft request' (duration: 87.897577ms)","trace[1127030774] 'compare' (duration: 29.399175ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:23.062529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.15366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:17:23.062665Z","caller":"traceutil/trace.go:171","msg":"trace[2045676422] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1030; }","duration":"358.140716ms","start":"2026-05-05T22:17:22.704512Z","end":"2026-05-05T22:17:23.062653Z","steps":["trace[2045676422] 'read index received' (duration: 222.775501ms)","trace[2045676422] 'applied index is now lower than readState.Index' (duration: 135.364515ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:23.062878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.20802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T22:17:23.062904Z","caller":"traceutil/trace.go:171","msg":"trace[1804651463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:995; }","duration":"351.247311ms","start":"2026-05-05T22:17:22.711648Z","end":"2026-05-05T22:17:23.062895Z","steps":["trace[1804651463] 'agreement among raft nodes before linearized reading' (duration: 351.161069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:23.062926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:22.71164Z","time spent":"351.280763ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T22:17:23.062944Z","caller":"traceutil/trace.go:171","msg":"trace[766433152] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"416.247369ms","start":"2026-05-05T22:17:22.646671Z","end":"2026-05-05T22:17:23.062918Z","steps":["trace[766433152] 'process raft request' (duration: 415.921182ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:23.062953Z","caller":"traceutil/trace.go:171","msg":"trace[1597519154] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"416.859704ms","start":"2026-05-05T22:17:22.646075Z","end":"2026-05-05T22:17:23.062935Z","steps":["trace[1597519154] 'process raft request' (duration: 281.234712ms)","trace[1597519154] 'compare' (duration: 135.012347ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:23.062957Z","caller":"traceutil/trace.go:171","msg":"trace[2027209646] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"384.352445ms","start":"2026-05-05T22:17:22.678585Z","end":"2026-05-05T22:17:23.062937Z","steps":["trace[2027209646] 'process raft request' (duration: 384.044097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:23.062976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.435803ms","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-05T22:17:23.063044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:22.646056Z","time spent":"416.945076ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:23.063049Z","caller":"traceutil/trace.go:171","msg":"trace[351448807] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:995; }","duration":"358.544716ms","start":"2026-05-05T22:17:22.704486Z","end":"2026-05-05T22:17:23.063031Z","steps":["trace[351448807] 'agreement among raft nodes before linearized reading' (duration: 358.29579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:23.063056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:22.646664Z","time spent":"416.327661ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:17:23.063067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:22.678575Z","time spent":"384.432267ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:17:23.063092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:22.704469Z","time spent":"358.614077ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T22:17:23.283167Z","caller":"traceutil/trace.go:171","msg":"trace[2029653532] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"215.721263ms","start":"2026-05-05T22:17:23.067424Z","end":"2026-05-05T22:17:23.283145Z","steps":["trace[2029653532] 'process raft request' (duration: 215.571449ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:23.285624Z","caller":"traceutil/trace.go:171","msg":"trace[2118367315] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"217.085655ms","start":"2026-05-05T22:17:23.068511Z","end":"2026-05-05T22:17:23.285597Z","steps":["trace[2118367315] 'process raft request' (duration: 216.777168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:31.438432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.561043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T22:17:31.438496Z","caller":"traceutil/trace.go:171","msg":"trace[171535116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1021; }","duration":"118.670806ms","start":"2026-05-05T22:17:31.319812Z","end":"2026-05-05T22:17:31.438483Z","steps":["trace[171535116] 'range keys from in-memory index tree' (duration: 118.420839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:31.438556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.223214ms","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-05T22:17:31.438639Z","caller":"traceutil/trace.go:171","msg":"trace[1359871727] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1021; }","duration":"123.335277ms","start":"2026-05-05T22:17:31.315282Z","end":"2026-05-05T22:17:31.438617Z","steps":["trace[1359871727] 'range keys from in-memory index tree' (duration: 123.03386ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:33.394219Z","caller":"traceutil/trace.go:171","msg":"trace[309350773] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"252.174316ms","start":"2026-05-05T22:17:33.142023Z","end":"2026-05-05T22:17:33.394197Z","steps":["trace[309350773] 'read index received' (duration: 252.102534ms)","trace[309350773] 'applied index is now lower than readState.Index' (duration: 70.742µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:33.394298Z","caller":"traceutil/trace.go:171","msg":"trace[183328557] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"274.512931ms","start":"2026-05-05T22:17:33.119762Z","end":"2026-05-05T22:17:33.394275Z","steps":["trace[183328557] 'process raft request' (duration: 274.309296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:33.394435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.391701ms","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-05T22:17:33.394483Z","caller":"traceutil/trace.go:171","msg":"trace[2044206327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1026; }","duration":"252.483614ms","start":"2026-05-05T22:17:33.141987Z","end":"2026-05-05T22:17:33.394471Z","steps":["trace[2044206327] 'agreement among raft nodes before linearized reading' (duration: 252.33364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:33.394562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.405087ms","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-05T22:17:33.394631Z","caller":"traceutil/trace.go:171","msg":"trace[115777678] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1026; }","duration":"251.52258ms","start":"2026-05-05T22:17:33.143086Z","end":"2026-05-05T22:17:33.394609Z","steps":["trace[115777678] 'agreement among raft nodes before linearized reading' (duration: 251.301945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:33.58524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.571979ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:17:33.58546Z","caller":"traceutil/trace.go:171","msg":"trace[2061694209] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"186.832299ms","start":"2026-05-05T22:17:33.398606Z","end":"2026-05-05T22:17:33.585438Z","steps":["trace[2061694209] 'process raft request' (duration: 68.976493ms)","trace[2061694209] 'compare' (duration: 117.416246ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:33.58546Z","caller":"traceutil/trace.go:171","msg":"trace[190398337] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"186.779448ms","start":"2026-05-05T22:17:33.39864Z","end":"2026-05-05T22:17:33.58542Z","steps":["trace[190398337] 'process raft request' (duration: 186.693726ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:33.988546Z","caller":"traceutil/trace.go:171","msg":"trace[1489813561] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"345.342489ms","start":"2026-05-05T22:17:33.643175Z","end":"2026-05-05T22:17:33.988517Z","steps":["trace[1489813561] 'process raft request' (duration: 345.292388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:33.988688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:33.643159Z","time spent":"345.454542ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:33.988735Z","caller":"traceutil/trace.go:171","msg":"trace[165727423] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"399.346244ms","start":"2026-05-05T22:17:33.589349Z","end":"2026-05-05T22:17:33.988695Z","steps":["trace[165727423] 'process raft request' (duration: 347.180994ms)","trace[165727423] 'compare' (duration: 51.829712ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:33.988894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:33.58934Z","time spent":"399.472077ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:17:39.899987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.076543ms","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-05T22:17:39.900069Z","caller":"traceutil/trace.go:171","msg":"trace[791207986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1046; }","duration":"292.201546ms","start":"2026-05-05T22:17:39.607847Z","end":"2026-05-05T22:17:39.900049Z","steps":["trace[791207986] 'range keys from in-memory index tree' (duration: 291.916229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:39.90011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.976881ms","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-05T22:17:39.900308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.510565ms","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-05T22:17:39.90035Z","caller":"traceutil/trace.go:171","msg":"trace[278829429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1046; }","duration":"290.570197ms","start":"2026-05-05T22:17:39.609769Z","end":"2026-05-05T22:17:39.900339Z","steps":["trace[278829429] 'range keys from in-memory index tree' (duration: 290.428043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:39.900596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.933113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T22:17:39.900706Z","caller":"traceutil/trace.go:171","msg":"trace[408418078] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1046; }","duration":"137.092466ms","start":"2026-05-05T22:17:39.76359Z","end":"2026-05-05T22:17:39.900682Z","steps":["trace[408418078] 'count revisions from in-memory index tree' (duration: 136.78363ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:39.90022Z","caller":"traceutil/trace.go:171","msg":"trace[2068148196] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1046; }","duration":"194.166886ms","start":"2026-05-05T22:17:39.706027Z","end":"2026-05-05T22:17:39.900194Z","steps":["trace[2068148196] 'range keys from in-memory index tree' (duration: 193.784206ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:40.18245Z","caller":"traceutil/trace.go:171","msg":"trace[1919425899] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"278.024355ms","start":"2026-05-05T22:17:39.904382Z","end":"2026-05-05T22:17:40.182406Z","steps":["trace[1919425899] 'process raft request' (duration: 277.867002ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:40.755255Z","caller":"traceutil/trace.go:171","msg":"trace[1303770735] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1088; }","duration":"742.009001ms","start":"2026-05-05T22:17:40.01322Z","end":"2026-05-05T22:17:40.755229Z","steps":["trace[1303770735] 'read index received' (duration: 169.203517ms)","trace[1303770735] 'applied index is now lower than readState.Index' (duration: 572.804324ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:40.755291Z","caller":"traceutil/trace.go:171","msg":"trace[1006664905] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"849.856827ms","start":"2026-05-05T22:17:39.905395Z","end":"2026-05-05T22:17:40.755252Z","steps":["trace[1006664905] 'process raft request' (duration: 849.615371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:40.755605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.365829ms","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-05T22:17:40.75568Z","caller":"traceutil/trace.go:171","msg":"trace[1275255119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1049; }","duration":"742.500353ms","start":"2026-05-05T22:17:40.013159Z","end":"2026-05-05T22:17:40.755659Z","steps":["trace[1275255119] 'agreement among raft nodes before linearized reading' (duration: 742.212366ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:40.755304Z","caller":"traceutil/trace.go:171","msg":"trace[1449366999] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"849.615261ms","start":"2026-05-05T22:17:39.905665Z","end":"2026-05-05T22:17:40.75528Z","steps":["trace[1449366999] 'process raft request' (duration: 849.520788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:40.755735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:40.013025Z","time spent":"742.697628ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T22:17:40.755741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:39.905367Z","time spent":"850.217996ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:17:40.755785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:39.905642Z","time spent":"850.090932ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:17:40.755961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.47596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:40.756051Z","caller":"traceutil/trace.go:171","msg":"trace[1232010392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1049; }","duration":"525.920121ms","start":"2026-05-05T22:17:40.230116Z","end":"2026-05-05T22:17:40.756036Z","steps":["trace[1232010392] 'agreement among raft nodes before linearized reading' (duration: 525.47946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:40.756319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:40.230097Z","time spent":"526.135885ms","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T22:17:41.696171Z","caller":"traceutil/trace.go:171","msg":"trace[480324202] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"274.063472ms","start":"2026-05-05T22:17:41.422081Z","end":"2026-05-05T22:17:41.696144Z","steps":["trace[480324202] 'process raft request' (duration: 273.916208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:42.556023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.314386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:42.556092Z","caller":"traceutil/trace.go:171","msg":"trace[381059534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1052; }","duration":"326.416969ms","start":"2026-05-05T22:17:42.22966Z","end":"2026-05-05T22:17:42.556077Z","steps":["trace[381059534] 'range keys from in-memory index tree' (duration: 326.241895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:42.556122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:42.229646Z","time spent":"326.47031ms","remote":"127.0.0.1:43932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T22:17:42.556173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.831243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T22:17:42.556239Z","caller":"traceutil/trace.go:171","msg":"trace[1441065688] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:1052; }","duration":"673.944926ms","start":"2026-05-05T22:17:41.882276Z","end":"2026-05-05T22:17:42.556221Z","steps":["trace[1441065688] 'range keys from in-memory index tree' (duration: 673.664089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:42.556275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:41.882252Z","time spent":"674.014677ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"warn","ts":"2026-05-05T22:17:42.556276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.254217ms","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-05T22:17:42.556391Z","caller":"traceutil/trace.go:171","msg":"trace[499117227] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1052; }","duration":"369.437062ms","start":"2026-05-05T22:17:42.186924Z","end":"2026-05-05T22:17:42.556361Z","steps":["trace[499117227] 'range keys from in-memory index tree' (duration: 368.993592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:42.556448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:42.186905Z","time spent":"369.532445ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T22:17:42.556458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.025345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:42.556488Z","caller":"traceutil/trace.go:171","msg":"trace[433875808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1052; }","duration":"519.092077ms","start":"2026-05-05T22:17:42.037389Z","end":"2026-05-05T22:17:42.556481Z","steps":["trace[433875808] 'range keys from in-memory index tree' (duration: 518.952673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:42.55651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:42.037367Z","time spent":"519.138197ms","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T22:17:42.928263Z","caller":"traceutil/trace.go:171","msg":"trace[724034727] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"367.155338ms","start":"2026-05-05T22:17:42.561074Z","end":"2026-05-05T22:17:42.92823Z","steps":["trace[724034727] 'process raft request' (duration: 333.018028ms)","trace[724034727] 'compare' (duration: 33.992096ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:42.92861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:42.561048Z","time spent":"367.290501ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:17:43.188252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.474697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:17:43.188639Z","caller":"traceutil/trace.go:171","msg":"trace[2091975594] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"580.207034ms","start":"2026-05-05T22:17:42.608383Z","end":"2026-05-05T22:17:43.18859Z","steps":["trace[2091975594] 'process raft request' (duration: 448.244545ms)","trace[2091975594] 'compare' (duration: 131.194501ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:43.188655Z","caller":"traceutil/trace.go:171","msg":"trace[1700168476] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1094; }","duration":"427.106307ms","start":"2026-05-05T22:17:42.761526Z","end":"2026-05-05T22:17:43.188632Z","steps":["trace[1700168476] 'read index received' (duration: 132.602535ms)","trace[1700168476] 'applied index is now lower than readState.Index' (duration: 294.501762ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:43.188857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:42.60837Z","time spent":"580.380138ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:17:43.188979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.441315ms","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-05T22:17:43.189043Z","caller":"traceutil/trace.go:171","msg":"trace[1992759519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1054; }","duration":"427.555998ms","start":"2026-05-05T22:17:42.761472Z","end":"2026-05-05T22:17:43.189028Z","steps":["trace[1992759519] 'agreement among raft nodes before linearized reading' (duration: 427.196659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:43.189065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.487732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-05T22:17:43.189101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:42.76145Z","time spent":"427.62657ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T22:17:43.189127Z","caller":"traceutil/trace.go:171","msg":"trace[975218613] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1054; }","duration":"426.613886ms","start":"2026-05-05T22:17:42.762501Z","end":"2026-05-05T22:17:43.189115Z","steps":["trace[975218613] 'agreement among raft nodes before linearized reading' (duration: 426.418741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:43.18917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:42.762468Z","time spent":"426.692037ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T22:17:43.189153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.160892ms","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-05T22:17:43.189225Z","caller":"traceutil/trace.go:171","msg":"trace[1623700763] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1054; }","duration":"256.301195ms","start":"2026-05-05T22:17:42.932906Z","end":"2026-05-05T22:17:43.189208Z","steps":["trace[1623700763] 'agreement among raft nodes before linearized reading' (duration: 256.023639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:43.189369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.492ms","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-05T22:17:43.189399Z","caller":"traceutil/trace.go:171","msg":"trace[2020978357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1054; }","duration":"403.605123ms","start":"2026-05-05T22:17:42.785787Z","end":"2026-05-05T22:17:43.189392Z","steps":["trace[2020978357] 'agreement among raft nodes before linearized reading' (duration: 403.50037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:43.18942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:42.785751Z","time spent":"403.663814ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T22:17:43.858339Z","caller":"traceutil/trace.go:171","msg":"trace[441827436] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"156.112989ms","start":"2026-05-05T22:17:43.702199Z","end":"2026-05-05T22:17:43.858312Z","steps":["trace[441827436] 'process raft request' (duration: 155.961536ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:44.719987Z","caller":"traceutil/trace.go:171","msg":"trace[1208943289] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1101; }","duration":"489.441915ms","start":"2026-05-05T22:17:44.230522Z","end":"2026-05-05T22:17:44.719964Z","steps":["trace[1208943289] 'read index received' (duration: 489.23815ms)","trace[1208943289] 'applied index is now lower than readState.Index' (duration: 202.945µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:44.720113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.567028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:44.720145Z","caller":"traceutil/trace.go:171","msg":"trace[629742595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1060; }","duration":"489.65786ms","start":"2026-05-05T22:17:44.230477Z","end":"2026-05-05T22:17:44.720135Z","steps":["trace[629742595] 'agreement among raft nodes before linearized reading' (duration: 489.582128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:44.72018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:44.230454Z","time spent":"489.717621ms","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T22:17:44.720282Z","caller":"traceutil/trace.go:171","msg":"trace[493738989] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"512.449256ms","start":"2026-05-05T22:17:44.207794Z","end":"2026-05-05T22:17:44.720243Z","steps":["trace[493738989] 'process raft request' (duration: 512.018226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:44.720447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:44.20777Z","time spent":"512.58409ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:51.394601Z","caller":"traceutil/trace.go:171","msg":"trace[410824136] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"117.917581ms","start":"2026-05-05T22:17:51.276654Z","end":"2026-05-05T22:17:51.394572Z","steps":["trace[410824136] 'process raft request' (duration: 33.548855ms)","trace[410824136] 'compare' (duration: 84.214843ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:51.520026Z","caller":"traceutil/trace.go:171","msg":"trace[1615225527] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"120.737109ms","start":"2026-05-05T22:17:51.39926Z","end":"2026-05-05T22:17:51.519997Z","steps":["trace[1615225527] 'process raft request' (duration: 118.881075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:56.013954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.550445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:56.014023Z","caller":"traceutil/trace.go:171","msg":"trace[1981428837] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1149; }","duration":"349.672538ms","start":"2026-05-05T22:17:55.664338Z","end":"2026-05-05T22:17:56.01401Z","steps":["trace[1981428837] 'count revisions from in-memory index tree' (duration: 349.455243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:56.014062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:55.664314Z","time spent":"349.740929ms","remote":"127.0.0.1:44254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-05-05T22:17:56.014273Z","caller":"traceutil/trace.go:171","msg":"trace[825460139] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"108.742592ms","start":"2026-05-05T22:17:55.905505Z","end":"2026-05-05T22:17:56.014248Z","steps":["trace[825460139] 'process raft request' (duration: 108.187489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:57.729555Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872540357524466,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T22:17:58.001353Z","caller":"traceutil/trace.go:171","msg":"trace[814840250] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1196; }","duration":"772.271416ms","start":"2026-05-05T22:17:57.229065Z","end":"2026-05-05T22:17:58.001336Z","steps":["trace[814840250] 'read index received' (duration: 772.163954ms)","trace[814840250] 'applied index is now lower than readState.Index' (duration: 106.882µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:58.001594Z","caller":"traceutil/trace.go:171","msg":"trace[628822446] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"922.871373ms","start":"2026-05-05T22:17:57.078716Z","end":"2026-05-05T22:17:58.001587Z","steps":["trace[628822446] 'process raft request' (duration: 922.510655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:58.001659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:57.078697Z","time spent":"922.916334ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:17:58.001825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.730197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:58.001924Z","caller":"traceutil/trace.go:171","msg":"trace[1983661101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1151; }","duration":"772.84738ms","start":"2026-05-05T22:17:57.229053Z","end":"2026-05-05T22:17:58.0019Z","steps":["trace[1983661101] 'agreement among raft nodes before linearized reading' (duration: 772.619784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:58.002013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:57.229002Z","time spent":"772.997614ms","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T22:17:58.002895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.191548ms","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-05T22:17:58.002925Z","caller":"traceutil/trace.go:171","msg":"trace[305344088] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1151; }","duration":"556.243528ms","start":"2026-05-05T22:17:57.446674Z","end":"2026-05-05T22:17:58.002917Z","steps":["trace[305344088] 'agreement among raft nodes before linearized reading' (duration: 556.155246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:58.002942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:57.44666Z","time spent":"556.27932ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T22:17:58.002956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.920139ms","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-05T22:17:58.003014Z","caller":"traceutil/trace.go:171","msg":"trace[298932809] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1151; }","duration":"460.998301ms","start":"2026-05-05T22:17:57.542001Z","end":"2026-05-05T22:17:58.002999Z","steps":["trace[298932809] 'agreement among raft nodes before linearized reading' (duration: 460.823157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:58.003061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:57.541983Z","time spent":"461.066873ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T22:17:58.003074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.656076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T22:17:58.003097Z","caller":"traceutil/trace.go:171","msg":"trace[568068108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1151; }","duration":"493.704867ms","start":"2026-05-05T22:17:57.509388Z","end":"2026-05-05T22:17:58.003093Z","steps":["trace[568068108] 'agreement among raft nodes before linearized reading' (duration: 493.41084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:58.003124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:57.509365Z","time spent":"493.754508ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T22:17:58.003526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.458351ms","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-05T22:17:58.003588Z","caller":"traceutil/trace.go:171","msg":"trace[284267078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1151; }","duration":"625.539423ms","start":"2026-05-05T22:17:57.378031Z","end":"2026-05-05T22:17:58.003571Z","steps":["trace[284267078] 'agreement among raft nodes before linearized reading' (duration: 625.39005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:58.003635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:57.378006Z","time spent":"625.618074ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T22:17:58.575749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.446661ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T22:17:58.575995Z","caller":"traceutil/trace.go:171","msg":"trace[136879606] linearizableReadLoop","detail":"{readStateIndex:1203; appliedIndex:1197; }","duration":"388.398318ms","start":"2026-05-05T22:17:58.187586Z","end":"2026-05-05T22:17:58.575984Z","steps":["trace[136879606] 'read index received' (duration: 386.497903ms)","trace[136879606] 'applied index is now lower than readState.Index' (duration: 1.898185ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:58.576055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.457299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:58.57607Z","caller":"traceutil/trace.go:171","msg":"trace[1362894056] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1156; }","duration":"388.517851ms","start":"2026-05-05T22:17:58.187547Z","end":"2026-05-05T22:17:58.576065Z","steps":["trace[1362894056] 'agreement among raft nodes before linearized reading' (duration: 388.46677ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:58.576125Z","caller":"traceutil/trace.go:171","msg":"trace[684885337] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"565.517011ms","start":"2026-05-05T22:17:58.010601Z","end":"2026-05-05T22:17:58.576118Z","steps":["trace[684885337] 'process raft request' (duration: 565.339247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:58.576179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:58.01059Z","time spent":"565.553472ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:58.576297Z","caller":"traceutil/trace.go:171","msg":"trace[1253572835] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"565.699915ms","start":"2026-05-05T22:17:58.010592Z","end":"2026-05-05T22:17:58.576292Z","steps":["trace[1253572835] 'process raft request' (duration: 565.328217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:58.576328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:58.010582Z","time spent":"565.731837ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:58.57615Z","caller":"traceutil/trace.go:171","msg":"trace[1017040482] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"565.667505ms","start":"2026-05-05T22:17:58.010451Z","end":"2026-05-05T22:17:58.576118Z","steps":["trace[1017040482] 'process raft request' (duration: 565.44551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:58.576385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:58.010426Z","time spent":"565.947111ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:58.576433Z","caller":"traceutil/trace.go:171","msg":"trace[70523700] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"565.993873ms","start":"2026-05-05T22:17:58.010435Z","end":"2026-05-05T22:17:58.576429Z","steps":["trace[70523700] 'process raft request' (duration: 565.382848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:58.576456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:58.010417Z","time spent":"566.028223ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:58.576569Z","caller":"traceutil/trace.go:171","msg":"trace[1274152687] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"557.834977ms","start":"2026-05-05T22:17:58.01873Z","end":"2026-05-05T22:17:58.576565Z","steps":["trace[1274152687] 'process raft request' (duration: 557.233653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:58.576598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:58.018719Z","time spent":"557.864107ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:17:58.576089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:58.187526Z","time spent":"388.558751ms","remote":"127.0.0.1:57972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-05T22:18:13.165708Z","caller":"traceutil/trace.go:171","msg":"trace[597183388] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"152.221516ms","start":"2026-05-05T22:18:13.013466Z","end":"2026-05-05T22:18:13.165688Z","steps":["trace[597183388] 'process raft request' (duration: 152.074092ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:14.312198Z","caller":"traceutil/trace.go:171","msg":"trace[697055477] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"194.612034ms","start":"2026-05-05T22:18:14.11757Z","end":"2026-05-05T22:18:14.312182Z","steps":["trace[697055477] 'process raft request' (duration: 194.304387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:15.141022Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872540357524634,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T22:18:15.188375Z","caller":"traceutil/trace.go:171","msg":"trace[161659794] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"558.116444ms","start":"2026-05-05T22:18:14.630245Z","end":"2026-05-05T22:18:15.188361Z","steps":["trace[161659794] 'process raft request' (duration: 557.866218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:15.188493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:14.630228Z","time spent":"558.193966ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:18:15.18877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.931249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T22:18:15.188826Z","caller":"traceutil/trace.go:171","msg":"trace[1107991155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1225; }","duration":"548.032922ms","start":"2026-05-05T22:18:14.64078Z","end":"2026-05-05T22:18:15.188813Z","steps":["trace[1107991155] 'agreement among raft nodes before linearized reading' (duration: 547.843537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:15.188849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:14.640756Z","time spent":"548.088913ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T22:18:15.188303Z","caller":"traceutil/trace.go:171","msg":"trace[1311689888] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1274; }","duration":"547.457508ms","start":"2026-05-05T22:18:14.640824Z","end":"2026-05-05T22:18:15.188281Z","steps":["trace[1311689888] 'read index received' (duration: 547.268054ms)","trace[1311689888] 'applied index is now lower than readState.Index' (duration: 188.404µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:18:15.200682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.707831ms","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-05T22:18:15.200756Z","caller":"traceutil/trace.go:171","msg":"trace[21990902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1226; }","duration":"554.809004ms","start":"2026-05-05T22:18:14.645932Z","end":"2026-05-05T22:18:15.200741Z","steps":["trace[21990902] 'agreement among raft nodes before linearized reading' (duration: 554.63398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:15.200789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:14.645922Z","time spent":"554.859625ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T22:18:15.2012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.522971ms","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-05T22:18:15.201239Z","caller":"traceutil/trace.go:171","msg":"trace[469382403] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1226; }","duration":"545.582233ms","start":"2026-05-05T22:18:14.655647Z","end":"2026-05-05T22:18:15.201229Z","steps":["trace[469382403] 'agreement among raft nodes before linearized reading' (duration: 545.489451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:15.201264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:14.655636Z","time spent":"545.620724ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T22:18:15.20141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.743532ms","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-05T22:18:15.201455Z","caller":"traceutil/trace.go:171","msg":"trace[67387866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1226; }","duration":"549.807004ms","start":"2026-05-05T22:18:14.65164Z","end":"2026-05-05T22:18:15.201447Z","steps":["trace[67387866] 'agreement among raft nodes before linearized reading' (duration: 549.721592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:15.201483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:14.651629Z","time spent":"549.844665ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T22:18:15.469682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.682138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:18:15.46989Z","caller":"traceutil/trace.go:171","msg":"trace[143568859] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"273.124119ms","start":"2026-05-05T22:18:15.196746Z","end":"2026-05-05T22:18:15.46987Z","steps":["trace[143568859] 'process raft request' (duration: 273.035667ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:15.469906Z","caller":"traceutil/trace.go:171","msg":"trace[1558726009] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"275.855515ms","start":"2026-05-05T22:18:15.194019Z","end":"2026-05-05T22:18:15.469874Z","steps":["trace[1558726009] 'process raft request' (duration: 57.86465ms)","trace[1558726009] 'compare' (duration: 217.422831ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:18:15.486608Z","caller":"traceutil/trace.go:171","msg":"trace[864484483] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"280.524157ms","start":"2026-05-05T22:18:15.206054Z","end":"2026-05-05T22:18:15.486578Z","steps":["trace[864484483] 'process raft request' (duration: 280.294221ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:15.48683Z","caller":"traceutil/trace.go:171","msg":"trace[1252099829] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"280.670631ms","start":"2026-05-05T22:18:15.206146Z","end":"2026-05-05T22:18:15.486817Z","steps":["trace[1252099829] 'process raft request' (duration: 280.63063ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:15.486998Z","caller":"traceutil/trace.go:171","msg":"trace[2116900840] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"280.876806ms","start":"2026-05-05T22:18:15.206113Z","end":"2026-05-05T22:18:15.486989Z","steps":["trace[2116900840] 'process raft request' (duration: 280.398154ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:16.186356Z","caller":"traceutil/trace.go:171","msg":"trace[1461911632] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"298.689763ms","start":"2026-05-05T22:18:15.88765Z","end":"2026-05-05T22:18:16.18634Z","steps":["trace[1461911632] 'process raft request' (duration: 298.631302ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:16.186514Z","caller":"traceutil/trace.go:171","msg":"trace[536125435] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"301.92623ms","start":"2026-05-05T22:18:15.884582Z","end":"2026-05-05T22:18:16.186508Z","steps":["trace[536125435] 'process raft request' (duration: 273.740864ms)","trace[536125435] 'compare' (duration: 27.630953ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:18:16.18656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:15.884563Z","time spent":"301.962392ms","remote":"127.0.0.1:44000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T22:18:16.637943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.122196ms","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-05T22:18:16.638019Z","caller":"traceutil/trace.go:171","msg":"trace[632840360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1237; }","duration":"322.245359ms","start":"2026-05-05T22:18:16.315757Z","end":"2026-05-05T22:18:16.638003Z","steps":["trace[632840360] 'range keys from in-memory index tree' (duration: 321.896791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:16.638058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:16.315738Z","time spent":"322.311431ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T22:18:16.638394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.146925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:18:16.63848Z","caller":"traceutil/trace.go:171","msg":"trace[44877025] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1237; }","duration":"212.275818ms","start":"2026-05-05T22:18:16.426185Z","end":"2026-05-05T22:18:16.63846Z","steps":["trace[44877025] 'count revisions from in-memory index tree' (duration: 212.036583ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:17.601719Z","caller":"traceutil/trace.go:171","msg":"trace[1494476487] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1290; }","duration":"126.958468ms","start":"2026-05-05T22:18:17.474736Z","end":"2026-05-05T22:18:17.601695Z","steps":["trace[1494476487] 'read index received' (duration: 121.313753ms)","trace[1494476487] 'applied index is now lower than readState.Index' (duration: 5.643695ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:18:17.60177Z","caller":"traceutil/trace.go:171","msg":"trace[231386834] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"386.333428ms","start":"2026-05-05T22:18:17.215407Z","end":"2026-05-05T22:18:17.601741Z","steps":["trace[231386834] 'process raft request' (duration: 380.60205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:17.601904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:17.215384Z","time spent":"386.4492ms","remote":"127.0.0.1:44110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5883,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:18:17.601907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.155643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T22:18:17.601955Z","caller":"traceutil/trace.go:171","msg":"trace[1223842126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1240; }","duration":"127.247165ms","start":"2026-05-05T22:18:17.474697Z","end":"2026-05-05T22:18:17.601944Z","steps":["trace[1223842126] 'agreement among raft nodes before linearized reading' (duration: 127.116612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:17.602035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.015795ms","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-05T22:18:17.602077Z","caller":"traceutil/trace.go:171","msg":"trace[602491572] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1240; }","duration":"111.106107ms","start":"2026-05-05T22:18:17.490959Z","end":"2026-05-05T22:18:17.602065Z","steps":["trace[602491572] 'agreement among raft nodes before linearized reading' (duration: 110.896972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:17.602293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.973945ms","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-05T22:18:17.602319Z","caller":"traceutil/trace.go:171","msg":"trace[1104977707] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1240; }","duration":"111.003065ms","start":"2026-05-05T22:18:17.49131Z","end":"2026-05-05T22:18:17.602313Z","steps":["trace[1104977707] 'agreement among raft nodes before linearized reading' (duration: 110.937064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:17.602083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.219396ms","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-05T22:18:17.60239Z","caller":"traceutil/trace.go:171","msg":"trace[282118381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1240; }","duration":"110.539824ms","start":"2026-05-05T22:18:17.49181Z","end":"2026-05-05T22:18:17.60235Z","steps":["trace[282118381] 'agreement among raft nodes before linearized reading' (duration: 110.064573ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:18.128151Z","caller":"traceutil/trace.go:171","msg":"trace[6431427] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1292; }","duration":"388.326296ms","start":"2026-05-05T22:18:17.739805Z","end":"2026-05-05T22:18:18.128131Z","steps":["trace[6431427] 'read index received' (duration: 388.06932ms)","trace[6431427] 'applied index is now lower than readState.Index' (duration: 256.156µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:18:18.128285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.459329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:18:18.128294Z","caller":"traceutil/trace.go:171","msg":"trace[1722315244] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"521.917585ms","start":"2026-05-05T22:18:17.606357Z","end":"2026-05-05T22:18:18.128274Z","steps":["trace[1722315244] 'process raft request' (duration: 521.580657ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:18.128318Z","caller":"traceutil/trace.go:171","msg":"trace[1163901556] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1244; }","duration":"388.53685ms","start":"2026-05-05T22:18:17.739772Z","end":"2026-05-05T22:18:18.128309Z","steps":["trace[1163901556] 'agreement among raft nodes before linearized reading' (duration: 388.444668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:18.128351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:17.739756Z","time spent":"388.585232ms","remote":"127.0.0.1:60466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:18:18.128402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:17.606333Z","time spent":"522.006506ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:18:18.128551Z","caller":"traceutil/trace.go:171","msg":"trace[224815101] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"520.777787ms","start":"2026-05-05T22:18:17.607762Z","end":"2026-05-05T22:18:18.12854Z","steps":["trace[224815101] 'process raft request' (duration: 520.299836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:18.128611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:17.607749Z","time spent":"520.824968ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:18:18.128686Z","caller":"traceutil/trace.go:171","msg":"trace[1235962688] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"520.831488ms","start":"2026-05-05T22:18:17.607846Z","end":"2026-05-05T22:18:18.128678Z","steps":["trace[1235962688] 'process raft request' (duration: 520.254204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:18.128732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:17.607827Z","time spent":"520.880479ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:18:20.248502Z","caller":"traceutil/trace.go:171","msg":"trace[451573724] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"112.257256ms","start":"2026-05-05T22:18:20.13622Z","end":"2026-05-05T22:18:20.248478Z","steps":["trace[451573724] 'process raft request' (duration: 112.133673ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:20.253329Z","caller":"traceutil/trace.go:171","msg":"trace[1561309737] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"116.628071ms","start":"2026-05-05T22:18:20.136636Z","end":"2026-05-05T22:18:20.253264Z","steps":["trace[1561309737] 'process raft request' (duration: 116.375585ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:20.253414Z","caller":"traceutil/trace.go:171","msg":"trace[1854537165] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"115.946535ms","start":"2026-05-05T22:18:20.137433Z","end":"2026-05-05T22:18:20.25338Z","steps":["trace[1854537165] 'process raft request' (duration: 115.75869ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:27.008541Z","caller":"traceutil/trace.go:171","msg":"trace[2003673998] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"145.407762ms","start":"2026-05-05T22:18:26.863113Z","end":"2026-05-05T22:18:27.008521Z","steps":["trace[2003673998] 'process raft request' (duration: 145.28802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:28.368881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.288322ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T22:18:28.369278Z","caller":"traceutil/trace.go:171","msg":"trace[1431284482] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1362; }","duration":"1.300369278s","start":"2026-05-05T22:18:27.068858Z","end":"2026-05-05T22:18:28.369227Z","steps":["trace[1431284482] 'read index received' (duration: 309.733288ms)","trace[1431284482] 'applied index is now lower than readState.Index' (duration: 990.62969ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:18:28.369525Z","caller":"traceutil/trace.go:171","msg":"trace[1076622888] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"1.077850915s","start":"2026-05-05T22:18:27.291537Z","end":"2026-05-05T22:18:28.369388Z","steps":["trace[1076622888] 'process raft request' (duration: 1.077687481s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:28.369592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138342768s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:18:28.369643Z","caller":"traceutil/trace.go:171","msg":"trace[1389964152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1311; }","duration":"1.138482931s","start":"2026-05-05T22:18:27.231147Z","end":"2026-05-05T22:18:28.36963Z","steps":["trace[1389964152] 'agreement among raft nodes before linearized reading' (duration: 1.138343778s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:28.369687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:27.231112Z","time spent":"1.138566233s","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T22:18:28.369888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:27.291514Z","time spent":"1.07806106s","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:18:28.370271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.09464ms","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-05T22:18:28.370278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.854369ms","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:526"} {"level":"info","ts":"2026-05-05T22:18:28.370364Z","caller":"traceutil/trace.go:171","msg":"trace[470482202] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1311; }","duration":"458.993702ms","start":"2026-05-05T22:18:27.911344Z","end":"2026-05-05T22:18:28.370338Z","steps":["trace[470482202] 'agreement among raft nodes before linearized reading' (duration: 458.779217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:28.370412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:27.911316Z","time spent":"459.087795ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-05T22:18:28.370443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.991892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:18:28.370313Z","caller":"traceutil/trace.go:171","msg":"trace[1828757932] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1311; }","duration":"697.190332ms","start":"2026-05-05T22:18:27.673112Z","end":"2026-05-05T22:18:28.370302Z","steps":["trace[1828757932] 'agreement among raft nodes before linearized reading' (duration: 697.08319ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:28.37049Z","caller":"traceutil/trace.go:171","msg":"trace[1815070033] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1311; }","duration":"434.084894ms","start":"2026-05-05T22:18:27.936395Z","end":"2026-05-05T22:18:28.370479Z","steps":["trace[1815070033] 'agreement among raft nodes before linearized reading' (duration: 434.011293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:28.370524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:27.936371Z","time spent":"434.143945ms","remote":"127.0.0.1:44064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:18:28.370546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:27.673053Z","time spent":"697.464259ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T22:18:28.371124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.302250944s","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:530"} {"level":"info","ts":"2026-05-05T22:18:28.371339Z","caller":"traceutil/trace.go:171","msg":"trace[1963726837] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1311; }","duration":"1.302472339s","start":"2026-05-05T22:18:27.068774Z","end":"2026-05-05T22:18:28.371247Z","steps":["trace[1963726837] 'agreement among raft nodes before linearized reading' (duration: 1.300567273s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:28.371493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:27.068741Z","time spent":"1.302711535s","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-05-05T22:19:22.88003Z","caller":"traceutil/trace.go:171","msg":"trace[2026104893] transaction","detail":"{read_only:false; response_revision:1564; number_of_response:1; }","duration":"149.578601ms","start":"2026-05-05T22:19:22.730419Z","end":"2026-05-05T22:19:22.879998Z","steps":["trace[2026104893] 'process raft request' (duration: 98.554893ms)","trace[2026104893] 'compare' (duration: 50.412123ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:19:22.88035Z","caller":"traceutil/trace.go:171","msg":"trace[1539804890] transaction","detail":"{read_only:false; response_revision:1567; number_of_response:1; }","duration":"148.764131ms","start":"2026-05-05T22:19:22.731563Z","end":"2026-05-05T22:19:22.880327Z","steps":["trace[1539804890] 'process raft request' (duration: 148.71909ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:22.880423Z","caller":"traceutil/trace.go:171","msg":"trace[1312220968] transaction","detail":"{read_only:false; response_revision:1566; number_of_response:1; }","duration":"148.991267ms","start":"2026-05-05T22:19:22.731391Z","end":"2026-05-05T22:19:22.880383Z","steps":["trace[1312220968] 'process raft request' (duration: 148.835113ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:22.880606Z","caller":"traceutil/trace.go:171","msg":"trace[166161601] transaction","detail":"{read_only:false; response_revision:1565; number_of_response:1; }","duration":"149.579761ms","start":"2026-05-05T22:19:22.731003Z","end":"2026-05-05T22:19:22.880583Z","steps":["trace[166161601] 'process raft request' (duration: 148.897364ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:25.007407Z","caller":"traceutil/trace.go:171","msg":"trace[1739429736] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"118.414586ms","start":"2026-05-05T22:19:24.888968Z","end":"2026-05-05T22:19:25.007382Z","steps":["trace[1739429736] 'process raft request' (duration: 118.329864ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:25.007442Z","caller":"traceutil/trace.go:171","msg":"trace[405985166] transaction","detail":"{read_only:false; response_revision:1573; number_of_response:1; }","duration":"118.480888ms","start":"2026-05-05T22:19:24.888901Z","end":"2026-05-05T22:19:25.007382Z","steps":["trace[405985166] 'process raft request' (duration: 111.177844ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:25.007557Z","caller":"traceutil/trace.go:171","msg":"trace[913868175] linearizableReadLoop","detail":"{readStateIndex:1639; appliedIndex:1635; }","duration":"117.611107ms","start":"2026-05-05T22:19:24.889923Z","end":"2026-05-05T22:19:25.007534Z","steps":["trace[913868175] 'read index received' (duration: 21.616626ms)","trace[913868175] 'applied index is now lower than readState.Index' (duration: 95.993211ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:19:25.007761Z","caller":"traceutil/trace.go:171","msg":"trace[892382848] transaction","detail":"{read_only:false; response_revision:1575; number_of_response:1; }","duration":"118.034077ms","start":"2026-05-05T22:19:24.889715Z","end":"2026-05-05T22:19:25.007749Z","steps":["trace[892382848] 'process raft request' (duration: 117.623217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:19:25.007911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.977996ms","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-05T22:19:25.00796Z","caller":"traceutil/trace.go:171","msg":"trace[1220460838] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1575; }","duration":"118.1448ms","start":"2026-05-05T22:19:24.889801Z","end":"2026-05-05T22:19:25.007945Z","steps":["trace[1220460838] 'agreement among raft nodes before linearized reading' (duration: 117.965936ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:32.900119Z","caller":"traceutil/trace.go:171","msg":"trace[1534885463] transaction","detail":"{read_only:false; response_revision:1609; number_of_response:1; }","duration":"124.076122ms","start":"2026-05-05T22:19:32.776018Z","end":"2026-05-05T22:19:32.900094Z","steps":["trace[1534885463] 'process raft request' (duration: 123.925938ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:20:05.398414Z","caller":"traceutil/trace.go:171","msg":"trace[1440644243] transaction","detail":"{read_only:false; response_revision:1758; number_of_response:1; }","duration":"111.693926ms","start":"2026-05-05T22:20:05.286683Z","end":"2026-05-05T22:20:05.398377Z","steps":["trace[1440644243] 'process raft request' (duration: 105.102609ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:20:13.549028Z","caller":"traceutil/trace.go:171","msg":"trace[1255520278] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"107.732832ms","start":"2026-05-05T22:20:13.441269Z","end":"2026-05-05T22:20:13.549002Z","steps":["trace[1255520278] 'process raft request' (duration: 87.956063ms)","trace[1255520278] 'compare' (duration: 19.596795ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:20:13.549241Z","caller":"traceutil/trace.go:171","msg":"trace[1133614400] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"106.648106ms","start":"2026-05-05T22:20:13.442521Z","end":"2026-05-05T22:20:13.549169Z","steps":["trace[1133614400] 'process raft request' (duration: 106.437181ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:20:54.171285Z","caller":"traceutil/trace.go:171","msg":"trace[135550003] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"327.128518ms","start":"2026-05-05T22:20:53.844141Z","end":"2026-05-05T22:20:54.171269Z","steps":["trace[135550003] 'process raft request' (duration: 327.037866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:20:54.171399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:20:53.844123Z","time spent":"327.21519ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:21:01.959902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.515468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:21:01.960026Z","caller":"traceutil/trace.go:171","msg":"trace[1304634763] linearizableReadLoop","detail":"{readStateIndex:2099; appliedIndex:2098; }","duration":"295.744408ms","start":"2026-05-05T22:21:01.664264Z","end":"2026-05-05T22:21:01.960009Z","steps":["trace[1304634763] 'read index received' (duration: 57.926464ms)","trace[1304634763] 'applied index is now lower than readState.Index' (duration: 237.816654ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:21:01.960054Z","caller":"traceutil/trace.go:171","msg":"trace[377517981] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"353.021647ms","start":"2026-05-05T22:21:01.607013Z","end":"2026-05-05T22:21:01.960035Z","steps":["trace[377517981] 'process raft request' (duration: 115.265894ms)","trace[377517981] 'compare' (duration: 237.389635ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:21:01.960118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:21:01.606996Z","time spent":"353.092158ms","remote":"127.0.0.1:44216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:21:01.960202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.827267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-05T22:21:01.960208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.945103ms","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-05T22:21:01.96024Z","caller":"traceutil/trace.go:171","msg":"trace[402266508] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2016; }","duration":"296.022364ms","start":"2026-05-05T22:21:01.664208Z","end":"2026-05-05T22:21:01.96023Z","steps":["trace[402266508] 'agreement among raft nodes before linearized reading' (duration: 295.891701ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:21:01.960232Z","caller":"traceutil/trace.go:171","msg":"trace[1134238906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2016; }","duration":"168.917978ms","start":"2026-05-05T22:21:01.791304Z","end":"2026-05-05T22:21:01.960222Z","steps":["trace[1134238906] 'agreement among raft nodes before linearized reading' (duration: 168.810906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:21:01.960417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.175121ms","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:531"} {"level":"info","ts":"2026-05-05T22:21:01.960505Z","caller":"traceutil/trace.go:171","msg":"trace[2142636058] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2016; }","duration":"115.321374ms","start":"2026-05-05T22:21:01.84516Z","end":"2026-05-05T22:21:01.960482Z","steps":["trace[2142636058] 'agreement among raft nodes before linearized reading' (duration: 115.12206ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:21:02.066484Z","caller":"traceutil/trace.go:171","msg":"trace[562856691] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"102.51717ms","start":"2026-05-05T22:21:01.963949Z","end":"2026-05-05T22:21:02.066466Z","steps":["trace[562856691] 'process raft request' (duration: 102.434268ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:21:02.066488Z","caller":"traceutil/trace.go:171","msg":"trace[1346283580] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"102.649733ms","start":"2026-05-05T22:21:01.963806Z","end":"2026-05-05T22:21:02.066455Z","steps":["trace[1346283580] 'process raft request' (duration: 101.051786ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:21:02.067019Z","caller":"traceutil/trace.go:171","msg":"trace[722385586] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"102.724384ms","start":"2026-05-05T22:21:01.96428Z","end":"2026-05-05T22:21:02.067004Z","steps":["trace[722385586] 'process raft request' (duration: 102.147281ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:21:10.397667Z","caller":"traceutil/trace.go:171","msg":"trace[1449315677] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"128.428396ms","start":"2026-05-05T22:21:10.269201Z","end":"2026-05-05T22:21:10.397629Z","steps":["trace[1449315677] 'process raft request' (duration: 84.073405ms)","trace[1449315677] 'compare' (duration: 44.180587ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:21:34.016046Z","caller":"traceutil/trace.go:171","msg":"trace[1776607714] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"114.764021ms","start":"2026-05-05T22:21:33.901258Z","end":"2026-05-05T22:21:34.016022Z","steps":["trace[1776607714] 'process raft request' (duration: 114.627608ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:21:41.308705Z","caller":"traceutil/trace.go:171","msg":"trace[1463574205] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"140.459344ms","start":"2026-05-05T22:21:41.168221Z","end":"2026-05-05T22:21:41.30868Z","steps":["trace[1463574205] 'process raft request' (duration: 139.989443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:21:41.538636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.995001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-05T22:21:41.538707Z","caller":"traceutil/trace.go:171","msg":"trace[858715395] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2226; }","duration":"193.132045ms","start":"2026-05-05T22:21:41.345559Z","end":"2026-05-05T22:21:41.538691Z","steps":["trace[858715395] 'range keys from in-memory index tree' (duration: 192.833218ms)"],"step_count":1}