{"level":"warn","ts":"2026-04-18T22:12:08.400764Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-18T22:12:08.400857Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132: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.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132: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-04-18T22:12:08.400928Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-18T22:12:08.400943Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-04-18T22:12:08.400967Z","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-04-18T22:12:08.401751Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-04-18T22:12:08.401852Z","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.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132: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-04-18T22:12:08.406248Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.13819ms"} {"level":"info","ts":"2026-04-18T22:12:08.418159Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-04-18T22:12:08.41828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-04-18T22:12:08.418313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-04-18T22:12:08.418334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-18T22:12:08.418352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-04-18T22:12:08.418453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-04-18T22:12:08.427159Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-18T22:12:08.429166Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-18T22:12:08.430204Z","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-04-18T22:12:08.432398Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-18T22:12:08.432508Z","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-04-18T22:12:08.432598Z","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-04-18T22:12:08.432658Z","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-04-18T22:12:08.432971Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-18T22:12:08.433465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-04-18T22:12:08.434001Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-04-18T22:12:08.437359Z","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-04-18T22:12:08.437632Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-04-18T22:12:08.43767Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-04-18T22:12:08.438301Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-18T22:12:08.438359Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-18T22:12:09.21971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-04-18T22:12:09.219783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-18T22:12:09.219811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-04-18T22:12:09.219843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-04-18T22:12:09.219855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-04-18T22:12:09.219866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-04-18T22:12:09.219881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-04-18T22:12:09.220489Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:12:09.220997Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-04-18T22:12:09.221024Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T22:12:09.221153Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T22:12:09.221347Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-18T22:12:09.221387Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-18T22:12:09.223215Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-18T22:12:09.226929Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:12:09.227029Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:12:09.227048Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:12:09.230912Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"warn","ts":"2026-04-18T22:12:35.556823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.473971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:12:35.557074Z","caller":"traceutil/trace.go:171","msg":"trace[189290110] linearizableReadLoop","detail":"{readStateIndex:471; appliedIndex:468; }","duration":"207.01456ms","start":"2026-04-18T22:12:35.350044Z","end":"2026-04-18T22:12:35.557059Z","steps":["trace[189290110] 'read index received' (duration: 39.864243ms)","trace[189290110] 'applied index is now lower than readState.Index' (duration: 167.149497ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:35.557155Z","caller":"traceutil/trace.go:171","msg":"trace[1539477690] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"222.483005ms","start":"2026-04-18T22:12:35.334663Z","end":"2026-04-18T22:12:35.557146Z","steps":["trace[1539477690] 'process raft request' (duration: 55.232327ms)","trace[1539477690] 'compare' (duration: 166.35832ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:35.557328Z","caller":"traceutil/trace.go:171","msg":"trace[602440363] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"217.774901ms","start":"2026-04-18T22:12:35.339544Z","end":"2026-04-18T22:12:35.557319Z","steps":["trace[602440363] 'process raft request' (duration: 217.473749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:35.557433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.380704ms","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-04-18T22:12:35.557457Z","caller":"traceutil/trace.go:171","msg":"trace[1569497635] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:456; }","duration":"207.461554ms","start":"2026-04-18T22:12:35.349988Z","end":"2026-04-18T22:12:35.557449Z","steps":["trace[1569497635] 'agreement among raft nodes before linearized reading' (duration: 207.402074ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:35.557547Z","caller":"traceutil/trace.go:171","msg":"trace[940054235] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"219.677919ms","start":"2026-04-18T22:12:35.337845Z","end":"2026-04-18T22:12:35.557523Z","steps":["trace[940054235] 'process raft request' (duration: 219.092153ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:35.647285Z","caller":"traceutil/trace.go:171","msg":"trace[606645953] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"289.892897ms","start":"2026-04-18T22:12:35.357362Z","end":"2026-04-18T22:12:35.647255Z","steps":["trace[606645953] 'process raft request' (duration: 289.747495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:35.647654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.494182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14401"} {"level":"info","ts":"2026-04-18T22:12:35.647782Z","caller":"traceutil/trace.go:171","msg":"trace[1061976139] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:457; }","duration":"289.673304ms","start":"2026-04-18T22:12:35.358077Z","end":"2026-04-18T22:12:35.64775Z","steps":["trace[1061976139] 'agreement among raft nodes before linearized reading' (duration: 289.281451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:35.64817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.475404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-04-18T22:12:35.648324Z","caller":"traceutil/trace.go:171","msg":"trace[111117631] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:457; }","duration":"223.678226ms","start":"2026-04-18T22:12:35.424619Z","end":"2026-04-18T22:12:35.648297Z","steps":["trace[111117631] 'agreement among raft nodes before linearized reading' (duration: 222.654987ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:35.679597Z","caller":"traceutil/trace.go:171","msg":"trace[725465511] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"118.287658ms","start":"2026-04-18T22:12:35.561276Z","end":"2026-04-18T22:12:35.679563Z","steps":["trace[725465511] 'process raft request' (duration: 112.560275ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:35.679876Z","caller":"traceutil/trace.go:171","msg":"trace[1983455358] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"117.008207ms","start":"2026-04-18T22:12:35.562845Z","end":"2026-04-18T22:12:35.679853Z","steps":["trace[1983455358] 'process raft request' (duration: 116.635234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:35.680038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.70659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-04-18T22:12:35.680074Z","caller":"traceutil/trace.go:171","msg":"trace[2069271767] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:460; }","duration":"122.796821ms","start":"2026-04-18T22:12:35.557265Z","end":"2026-04-18T22:12:35.680062Z","steps":["trace[2069271767] 'agreement among raft nodes before linearized reading' (duration: 122.700059ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:35.680295Z","caller":"traceutil/trace.go:171","msg":"trace[886771564] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"113.258392ms","start":"2026-04-18T22:12:35.567027Z","end":"2026-04-18T22:12:35.680285Z","steps":["trace[886771564] 'process raft request' (duration: 112.509035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:35.68046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.84554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-04-18T22:12:35.68049Z","caller":"traceutil/trace.go:171","msg":"trace[1531160921] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:460; }","duration":"122.875181ms","start":"2026-04-18T22:12:35.557607Z","end":"2026-04-18T22:12:35.680483Z","steps":["trace[1531160921] 'agreement among raft nodes before linearized reading' (duration: 122.77851ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:37.298097Z","caller":"traceutil/trace.go:171","msg":"trace[966724490] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"173.99979ms","start":"2026-04-18T22:12:37.124075Z","end":"2026-04-18T22:12:37.298075Z","steps":["trace[966724490] 'process raft request' (duration: 173.816667ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:37.306867Z","caller":"traceutil/trace.go:171","msg":"trace[52680037] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"181.015303ms","start":"2026-04-18T22:12:37.125839Z","end":"2026-04-18T22:12:37.306854Z","steps":["trace[52680037] 'process raft request' (duration: 180.822092ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:37.306978Z","caller":"traceutil/trace.go:171","msg":"trace[947324371] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"181.065914ms","start":"2026-04-18T22:12:37.125906Z","end":"2026-04-18T22:12:37.306971Z","steps":["trace[947324371] 'process raft request' (duration: 180.862383ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:37.307308Z","caller":"traceutil/trace.go:171","msg":"trace[1543676139] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"170.952561ms","start":"2026-04-18T22:12:37.136343Z","end":"2026-04-18T22:12:37.307295Z","steps":["trace[1543676139] 'process raft request' (duration: 170.463346ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:37.307654Z","caller":"traceutil/trace.go:171","msg":"trace[2140646342] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"171.240673ms","start":"2026-04-18T22:12:37.136389Z","end":"2026-04-18T22:12:37.30763Z","steps":["trace[2140646342] 'process raft request' (duration: 170.748999ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:37.30785Z","caller":"traceutil/trace.go:171","msg":"trace[1751780411] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"171.011292ms","start":"2026-04-18T22:12:37.136827Z","end":"2026-04-18T22:12:37.307838Z","steps":["trace[1751780411] 'process raft request' (duration: 170.762519ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:37.307863Z","caller":"traceutil/trace.go:171","msg":"trace[1902976539] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"171.363675ms","start":"2026-04-18T22:12:37.136471Z","end":"2026-04-18T22:12:37.307834Z","steps":["trace[1902976539] 'process raft request' (duration: 171.072182ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:37.308007Z","caller":"traceutil/trace.go:171","msg":"trace[2044263000] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"167.187866ms","start":"2026-04-18T22:12:37.140812Z","end":"2026-04-18T22:12:37.308Z","steps":["trace[2044263000] 'process raft request' (duration: 166.863113ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:37.308281Z","caller":"traceutil/trace.go:171","msg":"trace[1469111200] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"157.734028ms","start":"2026-04-18T22:12:37.150536Z","end":"2026-04-18T22:12:37.30827Z","steps":["trace[1469111200] 'process raft request' (duration: 157.306485ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:37.308848Z","caller":"traceutil/trace.go:171","msg":"trace[426364349] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"141.981424ms","start":"2026-04-18T22:12:37.166848Z","end":"2026-04-18T22:12:37.308829Z","steps":["trace[426364349] 'process raft request' (duration: 141.180206ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:37.309071Z","caller":"traceutil/trace.go:171","msg":"trace[2032586257] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"122.694865ms","start":"2026-04-18T22:12:37.186366Z","end":"2026-04-18T22:12:37.309061Z","steps":["trace[2032586257] 'process raft request' (duration: 122.12581ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:41.203071Z","caller":"traceutil/trace.go:171","msg":"trace[518131434] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"324.449506ms","start":"2026-04-18T22:12:40.878596Z","end":"2026-04-18T22:12:41.203046Z","steps":["trace[518131434] 'read index received' (duration: 324.226013ms)","trace[518131434] 'applied index is now lower than readState.Index' (duration: 222.513µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:41.203398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.778969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-18T22:12:41.203443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.065775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-04-18T22:12:41.203497Z","caller":"traceutil/trace.go:171","msg":"trace[2014847945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:549; }","duration":"324.945099ms","start":"2026-04-18T22:12:40.878534Z","end":"2026-04-18T22:12:41.203479Z","steps":["trace[2014847945] 'agreement among raft nodes before linearized reading' (duration: 324.656227ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:41.203503Z","caller":"traceutil/trace.go:171","msg":"trace[2061118126] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:549; }","duration":"310.174815ms","start":"2026-04-18T22:12:40.893312Z","end":"2026-04-18T22:12:41.203487Z","steps":["trace[2061118126] 'agreement among raft nodes before linearized reading' (duration: 309.875363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:41.203596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:40.893301Z","time spent":"310.283586ms","remote":"127.0.0.1:48058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":151259,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-18T22:12:41.203595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:40.878522Z","time spent":"325.05957ms","remote":"127.0.0.1:48284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-18T22:12:41.203732Z","caller":"traceutil/trace.go:171","msg":"trace[1578354523] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"325.663156ms","start":"2026-04-18T22:12:40.877463Z","end":"2026-04-18T22:12:41.203126Z","steps":["trace[1578354523] 'process raft request' (duration: 325.446083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:41.204108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:40.877449Z","time spent":"326.370262ms","remote":"127.0.0.1:48284","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-04-18T22:12:41.546317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.839386ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:41.546519Z","caller":"traceutil/trace.go:171","msg":"trace[336349883] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:549; }","duration":"229.061928ms","start":"2026-04-18T22:12:41.317435Z","end":"2026-04-18T22:12:41.546497Z","steps":["trace[336349883] 'range keys from in-memory index tree' (duration: 228.826066ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:41.546599Z","caller":"traceutil/trace.go:171","msg":"trace[797881945] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"296.997341ms","start":"2026-04-18T22:12:41.249537Z","end":"2026-04-18T22:12:41.546534Z","steps":["trace[797881945] 'read index received' (duration: 266.718398ms)","trace[797881945] 'applied index is now lower than readState.Index' (duration: 30.277593ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:41.546712Z","caller":"traceutil/trace.go:171","msg":"trace[1507136635] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"338.305433ms","start":"2026-04-18T22:12:41.208387Z","end":"2026-04-18T22:12:41.546692Z","steps":["trace[1507136635] 'process raft request' (duration: 307.94062ms)","trace[1507136635] 'compare' (duration: 29.774789ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:41.546865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.317065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-04-18T22:12:41.546984Z","caller":"traceutil/trace.go:171","msg":"trace[608225169] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:550; }","duration":"297.425965ms","start":"2026-04-18T22:12:41.249499Z","end":"2026-04-18T22:12:41.546925Z","steps":["trace[608225169] 'agreement among raft nodes before linearized reading' (duration: 297.184822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:41.547046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:41.208367Z","time spent":"338.418175ms","remote":"127.0.0.1:48284","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-04-18T22:12:44.195441Z","caller":"traceutil/trace.go:171","msg":"trace[1832614189] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"283.926596ms","start":"2026-04-18T22:12:43.91149Z","end":"2026-04-18T22:12:44.195417Z","steps":["trace[1832614189] 'process raft request' (duration: 283.794645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:44.987355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.35019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:12:44.98748Z","caller":"traceutil/trace.go:171","msg":"trace[1425016385] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"781.613538ms","start":"2026-04-18T22:12:44.205844Z","end":"2026-04-18T22:12:44.987457Z","steps":["trace[1425016385] 'read index received' (duration: 279.003477ms)","trace[1425016385] 'applied index is now lower than readState.Index' (duration: 502.608591ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:44.98762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.765749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:44.987604Z","caller":"traceutil/trace.go:171","msg":"trace[1186078511] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"957.077796ms","start":"2026-04-18T22:12:44.030492Z","end":"2026-04-18T22:12:44.98757Z","steps":["trace[1186078511] 'process raft request' (duration: 454.420354ms)","trace[1186078511] 'compare' (duration: 502.218299ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:44.98768Z","caller":"traceutil/trace.go:171","msg":"trace[234555961] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:567; }","duration":"781.852011ms","start":"2026-04-18T22:12:44.205808Z","end":"2026-04-18T22:12:44.98766Z","steps":["trace[234555961] 'agreement among raft nodes before linearized reading' (duration: 781.762429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:44.987733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:44.20579Z","time spent":"781.930551ms","remote":"127.0.0.1:48334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":28,"request content":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-18T22:12:44.987749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:44.030475Z","time spent":"957.200758ms","remote":"127.0.0.1:48284","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-04-18T22:12:44.98803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.608485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:44.988073Z","caller":"traceutil/trace.go:171","msg":"trace[971279454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"258.684626ms","start":"2026-04-18T22:12:44.729378Z","end":"2026-04-18T22:12:44.988062Z","steps":["trace[971279454] 'agreement among raft nodes before linearized reading' (duration: 258.617915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:44.988064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.422738ms","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-04-18T22:12:44.988138Z","caller":"traceutil/trace.go:171","msg":"trace[48917449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:567; }","duration":"334.54843ms","start":"2026-04-18T22:12:44.653573Z","end":"2026-04-18T22:12:44.988122Z","steps":["trace[48917449] 'agreement among raft nodes before linearized reading' (duration: 334.337329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:44.988185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:44.653554Z","time spent":"334.624431ms","remote":"127.0.0.1:48284","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-04-18T22:12:44.988342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.458176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-c7s5v\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-18T22:12:44.988483Z","caller":"traceutil/trace.go:171","msg":"trace[676345787] range","detail":"{range_begin:/registry/pods/kube-system/cilium-c7s5v; range_end:; response_count:1; response_revision:567; }","duration":"292.615937ms","start":"2026-04-18T22:12:44.695811Z","end":"2026-04-18T22:12:44.988427Z","steps":["trace[676345787] 'agreement among raft nodes before linearized reading' (duration: 292.268514ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:45.498211Z","caller":"traceutil/trace.go:171","msg":"trace[1962748736] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"504.166652ms","start":"2026-04-18T22:12:44.994014Z","end":"2026-04-18T22:12:45.498181Z","steps":["trace[1962748736] 'process raft request' (duration: 425.263845ms)","trace[1962748736] 'compare' (duration: 78.784597ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:45.498434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:44.993983Z","time spent":"504.310123ms","remote":"127.0.0.1:48284","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-04-18T22:12:45.502901Z","caller":"traceutil/trace.go:171","msg":"trace[70708687] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"497.793776ms","start":"2026-04-18T22:12:45.005071Z","end":"2026-04-18T22:12:45.502865Z","steps":["trace[70708687] 'process raft request' (duration: 497.432733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:45.503152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:45.005031Z","time spent":"497.946858ms","remote":"127.0.0.1:48190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:12:45.503214Z","caller":"traceutil/trace.go:171","msg":"trace[2010467124] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:587; }","duration":"281.758468ms","start":"2026-04-18T22:12:45.221429Z","end":"2026-04-18T22:12:45.503187Z","steps":["trace[2010467124] 'read index received' (duration: 197.940308ms)","trace[2010467124] 'applied index is now lower than readState.Index' (duration: 83.81633ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:45.5035Z","caller":"traceutil/trace.go:171","msg":"trace[727811863] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"126.380116ms","start":"2026-04-18T22:12:45.377097Z","end":"2026-04-18T22:12:45.503477Z","steps":["trace[727811863] 'process raft request' (duration: 125.76392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:45.503493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.057791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-18T22:12:45.503579Z","caller":"traceutil/trace.go:171","msg":"trace[794940820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:569; }","duration":"282.201702ms","start":"2026-04-18T22:12:45.221363Z","end":"2026-04-18T22:12:45.503565Z","steps":["trace[794940820] 'agreement among raft nodes before linearized reading' (duration: 281.93211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:46.222324Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878500667483856,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T22:12:46.580523Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.013218081s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-18T22:12:46.954746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.919235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:12:46.955023Z","caller":"traceutil/trace.go:171","msg":"trace[1168267088] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:591; }","duration":"1.233372568s","start":"2026-04-18T22:12:45.721628Z","end":"2026-04-18T22:12:46.955Z","steps":["trace[1168267088] 'read index received' (duration: 859.02724ms)","trace[1168267088] 'applied index is now lower than readState.Index' (duration: 374.343778ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:46.95515Z","caller":"traceutil/trace.go:171","msg":"trace[1322834349] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"1.38766851s","start":"2026-04-18T22:12:45.567469Z","end":"2026-04-18T22:12:46.955137Z","steps":["trace[1322834349] 'process raft request' (duration: 1.387437959s)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:46.955208Z","caller":"traceutil/trace.go:171","msg":"trace[444491505] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"1.387984504s","start":"2026-04-18T22:12:45.567194Z","end":"2026-04-18T22:12:46.955178Z","steps":["trace[444491505] 'process raft request' (duration: 1.013522554s)","trace[444491505] 'compare' (duration: 373.729063ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:46.955253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:45.567459Z","time spent":"1.387731491s","remote":"127.0.0.1:48284","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-04-18T22:12:46.95526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.215677251s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:46.955337Z","caller":"traceutil/trace.go:171","msg":"trace[1405829708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"1.215793472s","start":"2026-04-18T22:12:45.739517Z","end":"2026-04-18T22:12:46.95531Z","steps":["trace[1405829708] 'agreement among raft nodes before linearized reading' (duration: 1.215666561s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:46.955347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:45.567167Z","time spent":"1.388114195s","remote":"127.0.0.1:48334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T22:12:46.955394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:45.739501Z","time spent":"1.215882653s","remote":"127.0.0.1:48006","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-04-18T22:12:46.955569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233942384s","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-04-18T22:12:46.955609Z","caller":"traceutil/trace.go:171","msg":"trace[2138938608] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"1.234020974s","start":"2026-04-18T22:12:45.721577Z","end":"2026-04-18T22:12:46.955597Z","steps":["trace[2138938608] 'agreement among raft nodes before linearized reading' (duration: 1.233847712s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:46.955644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:45.721556Z","time spent":"1.234081074s","remote":"127.0.0.1:48284","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-04-18T22:12:48.19732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.70661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:12:48.19742Z","caller":"traceutil/trace.go:171","msg":"trace[1222224909] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"330.866503ms","start":"2026-04-18T22:12:47.86654Z","end":"2026-04-18T22:12:48.197407Z","steps":["trace[1222224909] 'read index received' (duration: 141.9445ms)","trace[1222224909] 'applied index is now lower than readState.Index' (duration: 188.920873ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:48.197654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.996317ms","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":"warn","ts":"2026-04-18T22:12:48.197635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.993262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:48.197682Z","caller":"traceutil/trace.go:171","msg":"trace[214789456] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:584; }","duration":"234.048238ms","start":"2026-04-18T22:12:47.963624Z","end":"2026-04-18T22:12:48.197672Z","steps":["trace[214789456] 'agreement among raft nodes before linearized reading' (duration: 233.990647ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:48.197711Z","caller":"traceutil/trace.go:171","msg":"trace[1730465517] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:584; }","duration":"302.102703ms","start":"2026-04-18T22:12:47.895592Z","end":"2026-04-18T22:12:48.197694Z","steps":["trace[1730465517] 'agreement among raft nodes before linearized reading' (duration: 301.957852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:48.197752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:47.895579Z","time spent":"302.163753ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-04-18T22:12:48.19782Z","caller":"traceutil/trace.go:171","msg":"trace[198432309] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"333.88868ms","start":"2026-04-18T22:12:47.863923Z","end":"2026-04-18T22:12:48.197812Z","steps":["trace[198432309] 'process raft request' (duration: 144.630134ms)","trace[198432309] 'compare' (duration: 188.372848ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:48.197853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:47.863912Z","time spent":"333.924721ms","remote":"127.0.0.1:48362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T22:12:48.197941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.398187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:12:48.19795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.513201ms","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-04-18T22:12:48.197981Z","caller":"traceutil/trace.go:171","msg":"trace[1170932917] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:584; }","duration":"234.563152ms","start":"2026-04-18T22:12:47.963409Z","end":"2026-04-18T22:12:48.197972Z","steps":["trace[1170932917] 'agreement among raft nodes before linearized reading' (duration: 234.511941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:48.198132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.897254ms","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":"warn","ts":"2026-04-18T22:12:48.198149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.651599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:48.198172Z","caller":"traceutil/trace.go:171","msg":"trace[1580625717] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:584; }","duration":"212.687319ms","start":"2026-04-18T22:12:47.985479Z","end":"2026-04-18T22:12:48.198166Z","steps":["trace[1580625717] 'agreement among raft nodes before linearized reading' (duration: 212.638919ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:48.198178Z","caller":"traceutil/trace.go:171","msg":"trace[1545093053] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:584; }","duration":"234.965404ms","start":"2026-04-18T22:12:47.963202Z","end":"2026-04-18T22:12:48.198167Z","steps":["trace[1545093053] 'agreement among raft nodes before linearized reading' (duration: 234.889004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:48.198324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.856783ms","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":"warn","ts":"2026-04-18T22:12:48.198291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.63584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:48.198446Z","caller":"traceutil/trace.go:171","msg":"trace[609944980] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:584; }","duration":"212.871882ms","start":"2026-04-18T22:12:47.98555Z","end":"2026-04-18T22:12:48.198422Z","steps":["trace[609944980] 'agreement among raft nodes before linearized reading' (duration: 212.558679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:48.198497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.103423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:48.198538Z","caller":"traceutil/trace.go:171","msg":"trace[83279691] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:584; }","duration":"182.153204ms","start":"2026-04-18T22:12:48.016377Z","end":"2026-04-18T22:12:48.19853Z","steps":["trace[83279691] 'agreement among raft nodes before linearized reading' (duration: 182.098843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:48.198645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.065124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:48.198662Z","caller":"traceutil/trace.go:171","msg":"trace[1010648796] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:584; }","duration":"182.098064ms","start":"2026-04-18T22:12:48.016559Z","end":"2026-04-18T22:12:48.198657Z","steps":["trace[1010648796] 'agreement among raft nodes before linearized reading' (duration: 182.069593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:48.198881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.507886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:48.198921Z","caller":"traceutil/trace.go:171","msg":"trace[948999461] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:584; }","duration":"182.562159ms","start":"2026-04-18T22:12:48.016343Z","end":"2026-04-18T22:12:48.198905Z","steps":["trace[948999461] 'agreement among raft nodes before linearized reading' (duration: 182.504977ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:48.198356Z","caller":"traceutil/trace.go:171","msg":"trace[868637657] 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:584; }","duration":"259.923304ms","start":"2026-04-18T22:12:47.938422Z","end":"2026-04-18T22:12:48.198346Z","steps":["trace[868637657] 'agreement among raft nodes before linearized reading' (duration: 259.876314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:48.199063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.833982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:48.199087Z","caller":"traceutil/trace.go:171","msg":"trace[708456853] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:584; }","duration":"212.895292ms","start":"2026-04-18T22:12:47.986183Z","end":"2026-04-18T22:12:48.199078Z","steps":["trace[708456853] 'agreement among raft nodes before linearized reading' (duration: 212.854971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:48.19937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.693306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:48.199407Z","caller":"traceutil/trace.go:171","msg":"trace[1973540921] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:584; }","duration":"303.732526ms","start":"2026-04-18T22:12:47.895665Z","end":"2026-04-18T22:12:48.199397Z","steps":["trace[1973540921] 'agreement among raft nodes before linearized reading' (duration: 303.672926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:48.199439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:47.895654Z","time spent":"303.775486ms","remote":"127.0.0.1:36302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"info","ts":"2026-04-18T22:12:48.197959Z","caller":"traceutil/trace.go:171","msg":"trace[638483996] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:584; }","duration":"331.443768ms","start":"2026-04-18T22:12:47.86651Z","end":"2026-04-18T22:12:48.197954Z","steps":["trace[638483996] 'agreement among raft nodes before linearized reading' (duration: 331.409877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:48.199596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.022151ms","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-04-18T22:12:48.199609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:47.866495Z","time spent":"333.101313ms","remote":"127.0.0.1:36296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"info","ts":"2026-04-18T22:12:48.199628Z","caller":"traceutil/trace.go:171","msg":"trace[1255003316] 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:584; }","duration":"262.085443ms","start":"2026-04-18T22:12:47.93753Z","end":"2026-04-18T22:12:48.199616Z","steps":["trace[1255003316] 'agreement among raft nodes before linearized reading' (duration: 262.033721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:48.199774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.745057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:12:48.199768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.760917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:48.199878Z","caller":"traceutil/trace.go:171","msg":"trace[1022621443] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:584; }","duration":"279.904208ms","start":"2026-04-18T22:12:47.919953Z","end":"2026-04-18T22:12:48.199857Z","steps":["trace[1022621443] 'agreement among raft nodes before linearized reading' (duration: 279.688747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:48.199897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.644216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:48.199924Z","caller":"traceutil/trace.go:171","msg":"trace[1694068277] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:584; }","duration":"279.680467ms","start":"2026-04-18T22:12:47.920236Z","end":"2026-04-18T22:12:48.199916Z","steps":["trace[1694068277] 'agreement among raft nodes before linearized reading' (duration: 279.635617ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:48.199819Z","caller":"traceutil/trace.go:171","msg":"trace[2083340065] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:584; }","duration":"303.800207ms","start":"2026-04-18T22:12:47.89601Z","end":"2026-04-18T22:12:48.19981Z","steps":["trace[2083340065] 'agreement among raft nodes before linearized reading' (duration: 303.735527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:48.200069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:47.896006Z","time spent":"304.050049ms","remote":"127.0.0.1:36296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-18T22:12:48.200064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.997829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:48.200128Z","caller":"traceutil/trace.go:171","msg":"trace[1605825349] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:584; }","duration":"280.068129ms","start":"2026-04-18T22:12:47.920045Z","end":"2026-04-18T22:12:48.200113Z","steps":["trace[1605825349] 'agreement among raft nodes before linearized reading' (duration: 279.985639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:48.200642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.794412ms","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-04-18T22:12:48.200774Z","caller":"traceutil/trace.go:171","msg":"trace[1911334186] 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:584; }","duration":"263.120132ms","start":"2026-04-18T22:12:47.937585Z","end":"2026-04-18T22:12:48.200705Z","steps":["trace[1911334186] 'agreement among raft nodes before linearized reading' (duration: 260.790572ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:03.179508Z","caller":"traceutil/trace.go:171","msg":"trace[1315590088] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"451.460225ms","start":"2026-04-18T22:13:02.728011Z","end":"2026-04-18T22:13:03.179472Z","steps":["trace[1315590088] 'read index received' (duration: 451.378471ms)","trace[1315590088] 'applied index is now lower than readState.Index' (duration: 80.454µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:13:03.179657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.619552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:13:03.179747Z","caller":"traceutil/trace.go:171","msg":"trace[1109742411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"451.720566ms","start":"2026-04-18T22:13:02.728005Z","end":"2026-04-18T22:13:03.179726Z","steps":["trace[1109742411] 'agreement among raft nodes before linearized reading' (duration: 451.570569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:03.179799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:02.72797Z","time spent":"451.821519ms","remote":"127.0.0.1:48006","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-04-18T22:13:03.180257Z","caller":"traceutil/trace.go:171","msg":"trace[1147563232] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"615.602643ms","start":"2026-04-18T22:13:02.564635Z","end":"2026-04-18T22:13:03.180238Z","steps":["trace[1147563232] 'process raft request' (duration: 614.682442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:03.180369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:02.564614Z","time spent":"615.687876ms","remote":"127.0.0.1:48284","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-04-18T22:13:03.184486Z","caller":"traceutil/trace.go:171","msg":"trace[610703853] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"322.808305ms","start":"2026-04-18T22:13:02.861664Z","end":"2026-04-18T22:13:03.184472Z","steps":["trace[610703853] 'process raft request' (duration: 322.513212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:03.184566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:02.861643Z","time spent":"322.882388ms","remote":"127.0.0.1:48250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:13:03.184782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.233245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-18T22:13:03.184814Z","caller":"traceutil/trace.go:171","msg":"trace[510618644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:747; }","duration":"127.302999ms","start":"2026-04-18T22:13:03.057501Z","end":"2026-04-18T22:13:03.184804Z","steps":["trace[510618644] 'agreement among raft nodes before linearized reading' (duration: 127.201494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:03.185491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.414391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-18T22:13:03.185534Z","caller":"traceutil/trace.go:171","msg":"trace[481686419] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:747; }","duration":"135.496305ms","start":"2026-04-18T22:13:03.050028Z","end":"2026-04-18T22:13:03.185524Z","steps":["trace[481686419] 'agreement among raft nodes before linearized reading' (duration: 134.900499ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:10.073668Z","caller":"traceutil/trace.go:171","msg":"trace[2055708699] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"106.286909ms","start":"2026-04-18T22:13:09.967369Z","end":"2026-04-18T22:13:10.073656Z","steps":["trace[2055708699] 'process raft request' (duration: 106.046869ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:10.073582Z","caller":"traceutil/trace.go:171","msg":"trace[374532295] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"108.269081ms","start":"2026-04-18T22:13:09.965261Z","end":"2026-04-18T22:13:10.07353Z","steps":["trace[374532295] 'process raft request' (duration: 103.526575ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:16.903369Z","caller":"traceutil/trace.go:171","msg":"trace[157393102] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"100.479323ms","start":"2026-04-18T22:13:16.802866Z","end":"2026-04-18T22:13:16.903346Z","steps":["trace[157393102] 'read index received' (duration: 100.321517ms)","trace[157393102] 'applied index is now lower than readState.Index' (duration: 156.666µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:13:16.903411Z","caller":"traceutil/trace.go:171","msg":"trace[1917747086] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"112.558828ms","start":"2026-04-18T22:13:16.790813Z","end":"2026-04-18T22:13:16.903372Z","steps":["trace[1917747086] 'process raft request' (duration: 112.369841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:16.903605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.719352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/local-path-config\" ","response":"range_response_count:1 size:1464"} {"level":"info","ts":"2026-04-18T22:13:16.90365Z","caller":"traceutil/trace.go:171","msg":"trace[125940040] range","detail":"{range_begin:/registry/configmaps/local-path-storage/local-path-config; range_end:; response_count:1; response_revision:910; }","duration":"100.809146ms","start":"2026-04-18T22:13:16.802829Z","end":"2026-04-18T22:13:16.903638Z","steps":["trace[125940040] 'agreement among raft nodes before linearized reading' (duration: 100.614578ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:17.562644Z","caller":"traceutil/trace.go:171","msg":"trace[1720411441] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"100.412627ms","start":"2026-04-18T22:13:17.462208Z","end":"2026-04-18T22:13:17.562621Z","steps":["trace[1720411441] 'process raft request' (duration: 100.356445ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:17.562702Z","caller":"traceutil/trace.go:171","msg":"trace[1701529509] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"104.470563ms","start":"2026-04-18T22:13:17.458197Z","end":"2026-04-18T22:13:17.562668Z","steps":["trace[1701529509] 'process raft request' (duration: 104.245585ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:17.807901Z","caller":"traceutil/trace.go:171","msg":"trace[1034450337] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"125.885779ms","start":"2026-04-18T22:13:17.681991Z","end":"2026-04-18T22:13:17.807876Z","steps":["trace[1034450337] 'process raft request' (duration: 125.720823ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:17.811452Z","caller":"traceutil/trace.go:171","msg":"trace[601016554] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"128.367194ms","start":"2026-04-18T22:13:17.683053Z","end":"2026-04-18T22:13:17.81142Z","steps":["trace[601016554] 'process raft request' (duration: 128.115115ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:36.318003Z","caller":"traceutil/trace.go:171","msg":"trace[1985056250] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"369.018559ms","start":"2026-04-18T22:13:35.948962Z","end":"2026-04-18T22:13:36.31798Z","steps":["trace[1985056250] 'process raft request' (duration: 368.868543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:36.318143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:35.94895Z","time spent":"369.124113ms","remote":"127.0.0.1:48284","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-04-18T22:13:36.327331Z","caller":"traceutil/trace.go:171","msg":"trace[512999181] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1190; }","duration":"324.708278ms","start":"2026-04-18T22:13:36.002606Z","end":"2026-04-18T22:13:36.327314Z","steps":["trace[512999181] 'read index received' (duration: 315.228811ms)","trace[512999181] 'applied index is now lower than readState.Index' (duration: 9.478817ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:13:36.327383Z","caller":"traceutil/trace.go:171","msg":"trace[370846432] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"377.513354ms","start":"2026-04-18T22:13:35.949846Z","end":"2026-04-18T22:13:36.327359Z","steps":["trace[370846432] 'process raft request' (duration: 377.339829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:36.327448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.834502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-04-18T22:13:36.32748Z","caller":"traceutil/trace.go:171","msg":"trace[805649891] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1159; }","duration":"324.922764ms","start":"2026-04-18T22:13:36.002549Z","end":"2026-04-18T22:13:36.327472Z","steps":["trace[805649891] 'agreement among raft nodes before linearized reading' (duration: 324.836152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:36.327504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:36.002528Z","time spent":"324.969436ms","remote":"127.0.0.1:48208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":247,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" "} {"level":"warn","ts":"2026-04-18T22:13:36.327513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:35.949825Z","time spent":"377.613226ms","remote":"127.0.0.1:48284","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-04-18T22:13:36.327594Z","caller":"traceutil/trace.go:171","msg":"trace[933484629] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"223.291568ms","start":"2026-04-18T22:13:36.104286Z","end":"2026-04-18T22:13:36.327577Z","steps":["trace[933484629] 'process raft request' (duration: 223.002019ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:52.249195Z","caller":"traceutil/trace.go:171","msg":"trace[1245908506] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1336; }","duration":"118.395459ms","start":"2026-04-18T22:13:52.130777Z","end":"2026-04-18T22:13:52.249173Z","steps":["trace[1245908506] 'read index received' (duration: 118.214334ms)","trace[1245908506] 'applied index is now lower than readState.Index' (duration: 180.315µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:13:52.24929Z","caller":"traceutil/trace.go:171","msg":"trace[1510582926] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"166.651995ms","start":"2026-04-18T22:13:52.082612Z","end":"2026-04-18T22:13:52.249264Z","steps":["trace[1510582926] 'process raft request' (duration: 166.43047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:52.249399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.600836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-18T22:13:52.249474Z","caller":"traceutil/trace.go:171","msg":"trace[1911078881] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:1294; }","duration":"118.723459ms","start":"2026-04-18T22:13:52.130732Z","end":"2026-04-18T22:13:52.249456Z","steps":["trace[1911078881] 'agreement among raft nodes before linearized reading' (duration: 118.532803ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:57.723937Z","caller":"traceutil/trace.go:171","msg":"trace[1296961386] linearizableReadLoop","detail":"{readStateIndex:1456; appliedIndex:1455; }","duration":"130.104624ms","start":"2026-04-18T22:13:57.593816Z","end":"2026-04-18T22:13:57.72392Z","steps":["trace[1296961386] 'read index received' (duration: 118.28251ms)","trace[1296961386] 'applied index is now lower than readState.Index' (duration: 11.821334ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:13:57.724148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.306989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/ingress-nginx-admission\" ","response":"range_response_count:1 size:1823"} {"level":"info","ts":"2026-04-18T22:13:57.724221Z","caller":"traceutil/trace.go:171","msg":"trace[1024213374] range","detail":"{range_begin:/registry/secrets/ingress-nginx/ingress-nginx-admission; range_end:; response_count:1; response_revision:1410; }","duration":"130.438383ms","start":"2026-04-18T22:13:57.593769Z","end":"2026-04-18T22:13:57.724207Z","steps":["trace[1024213374] 'agreement among raft nodes before linearized reading' (duration: 130.227316ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:22.936853Z","caller":"traceutil/trace.go:171","msg":"trace[1150312415] linearizableReadLoop","detail":"{readStateIndex:1908; appliedIndex:1907; }","duration":"416.148423ms","start":"2026-04-18T22:14:22.520685Z","end":"2026-04-18T22:14:22.936833Z","steps":["trace[1150312415] 'read index received' (duration: 415.949119ms)","trace[1150312415] 'applied index is now lower than readState.Index' (duration: 198.434µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:14:22.936983Z","caller":"traceutil/trace.go:171","msg":"trace[916159457] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"445.664786ms","start":"2026-04-18T22:14:22.491295Z","end":"2026-04-18T22:14:22.93696Z","steps":["trace[916159457] 'process raft request' (duration: 445.410801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:14:22.937122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:14:22.491272Z","time spent":"445.764027ms","remote":"127.0.0.1:48284","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-04-18T22:14:22.937323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.627294ms","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-04-18T22:14:22.937352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.570702ms","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-04-18T22:14:22.9374Z","caller":"traceutil/trace.go:171","msg":"trace[2082441334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1835; }","duration":"415.648944ms","start":"2026-04-18T22:14:22.521738Z","end":"2026-04-18T22:14:22.937387Z","steps":["trace[2082441334] 'agreement among raft nodes before linearized reading' (duration: 415.528232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:14:22.937451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:14:22.521726Z","time spent":"415.715445ms","remote":"127.0.0.1:48284","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-04-18T22:14:22.937391Z","caller":"traceutil/trace.go:171","msg":"trace[1014587384] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1835; }","duration":"416.725456ms","start":"2026-04-18T22:14:22.520648Z","end":"2026-04-18T22:14:22.937374Z","steps":["trace[1014587384] 'agreement among raft nodes before linearized reading' (duration: 416.343157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:14:22.937614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.353119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-18T22:14:22.937685Z","caller":"traceutil/trace.go:171","msg":"trace[244451054] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1835; }","duration":"380.461361ms","start":"2026-04-18T22:14:22.557206Z","end":"2026-04-18T22:14:22.937667Z","steps":["trace[244451054] 'agreement among raft nodes before linearized reading' (duration: 380.272407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:14:22.937735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:14:22.557183Z","time spent":"380.542953ms","remote":"127.0.0.1:56614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-18T22:14:22.937742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.623777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-466ac302-6288-45e4-a86d-de87ada28769\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-18T22:14:22.937735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:14:22.520635Z","time spent":"416.96513ms","remote":"127.0.0.1:48284","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-04-18T22:14:22.937787Z","caller":"traceutil/trace.go:171","msg":"trace[1748586301] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-466ac302-6288-45e4-a86d-de87ada28769; range_end:; response_count:1; response_revision:1835; }","duration":"400.7694ms","start":"2026-04-18T22:14:22.537004Z","end":"2026-04-18T22:14:22.937774Z","steps":["trace[1748586301] 'agreement among raft nodes before linearized reading' (duration: 400.645197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:14:22.937839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:14:22.536981Z","time spent":"400.847991ms","remote":"127.0.0.1:48190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-466ac302-6288-45e4-a86d-de87ada28769\" "} {"level":"warn","ts":"2026-04-18T22:14:23.147458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.549409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:14:23.147684Z","caller":"traceutil/trace.go:171","msg":"trace[1956024477] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"201.884775ms","start":"2026-04-18T22:14:22.945782Z","end":"2026-04-18T22:14:23.147666Z","steps":["trace[1956024477] 'process raft request' (duration: 201.844104ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:23.14789Z","caller":"traceutil/trace.go:171","msg":"trace[1265793006] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"204.0286ms","start":"2026-04-18T22:14:22.943842Z","end":"2026-04-18T22:14:23.147871Z","steps":["trace[1265793006] 'process raft request' (duration: 38.90068ms)","trace[1265793006] 'compare' (duration: 164.432916ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:14:23.14796Z","caller":"traceutil/trace.go:171","msg":"trace[1771519368] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"203.996471ms","start":"2026-04-18T22:14:22.943951Z","end":"2026-04-18T22:14:23.147947Z","steps":["trace[1771519368] 'process raft request' (duration: 203.614592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:14:23.658514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.585663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:14:23.658685Z","caller":"traceutil/trace.go:171","msg":"trace[532279972] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"405.319475ms","start":"2026-04-18T22:14:23.253321Z","end":"2026-04-18T22:14:23.65864Z","steps":["trace[532279972] 'process raft request' (duration: 114.465767ms)","trace[532279972] 'compare' (duration: 289.990561ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:14:23.658904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:14:23.253295Z","time spent":"405.52064ms","remote":"127.0.0.1:48190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:14:35.364512Z","caller":"traceutil/trace.go:171","msg":"trace[1409170075] linearizableReadLoop","detail":"{readStateIndex:2014; appliedIndex:2013; }","duration":"342.646088ms","start":"2026-04-18T22:14:35.021833Z","end":"2026-04-18T22:14:35.364479Z","steps":["trace[1409170075] 'read index received' (duration: 342.424243ms)","trace[1409170075] 'applied index is now lower than readState.Index' (duration: 220.565µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:14:35.364667Z","caller":"traceutil/trace.go:171","msg":"trace[1810878665] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"373.824881ms","start":"2026-04-18T22:14:34.990827Z","end":"2026-04-18T22:14:35.364652Z","steps":["trace[1810878665] 'process raft request' (duration: 373.467854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:14:35.364939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.345692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-18T22:14:35.365011Z","caller":"traceutil/trace.go:171","msg":"trace[140131118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1938; }","duration":"167.469065ms","start":"2026-04-18T22:14:35.197525Z","end":"2026-04-18T22:14:35.364994Z","steps":["trace[140131118] 'agreement among raft nodes before linearized reading' (duration: 167.271561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:14:35.365031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.297611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-18T22:14:35.365064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.182999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-18T22:14:35.365107Z","caller":"traceutil/trace.go:171","msg":"trace[1166383656] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1938; }","duration":"166.445214ms","start":"2026-04-18T22:14:35.198641Z","end":"2026-04-18T22:14:35.365086Z","steps":["trace[1166383656] 'agreement among raft nodes before linearized reading' (duration: 166.238241ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:35.365191Z","caller":"traceutil/trace.go:171","msg":"trace[1716106902] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1938; }","duration":"343.371221ms","start":"2026-04-18T22:14:35.021777Z","end":"2026-04-18T22:14:35.365149Z","steps":["trace[1716106902] 'agreement among raft nodes before linearized reading' (duration: 343.078776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:14:35.36528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:14:35.021752Z","time spent":"343.484615ms","remote":"127.0.0.1:56614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-18T22:14:35.365593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:14:34.990796Z","time spent":"373.906763ms","remote":"127.0.0.1:48284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:14:38.285991Z","caller":"traceutil/trace.go:171","msg":"trace[1225005749] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"129.594768ms","start":"2026-04-18T22:14:38.156374Z","end":"2026-04-18T22:14:38.285969Z","steps":["trace[1225005749] 'process raft request' (duration: 127.514548ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:39.220457Z","caller":"traceutil/trace.go:171","msg":"trace[804393693] linearizableReadLoop","detail":"{readStateIndex:2037; appliedIndex:2036; }","duration":"496.845932ms","start":"2026-04-18T22:14:38.723574Z","end":"2026-04-18T22:14:39.22042Z","steps":["trace[804393693] 'read index received' (duration: 496.618938ms)","trace[804393693] 'applied index is now lower than readState.Index' (duration: 226.074µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:14:39.220609Z","caller":"traceutil/trace.go:171","msg":"trace[1904628617] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"565.497694ms","start":"2026-04-18T22:14:38.65509Z","end":"2026-04-18T22:14:39.220588Z","steps":["trace[1904628617] 'process raft request' (duration: 565.179837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:14:39.220664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.715096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:14:39.220718Z","caller":"traceutil/trace.go:171","msg":"trace[951960249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1960; }","duration":"491.808847ms","start":"2026-04-18T22:14:38.728893Z","end":"2026-04-18T22:14:39.220702Z","steps":["trace[951960249] 'agreement among raft nodes before linearized reading' (duration: 491.695295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:14:39.220719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.135728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-04-18T22:14:39.220731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:14:38.655072Z","time spent":"565.580245ms","remote":"127.0.0.1:48190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5623,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:14:39.220753Z","caller":"traceutil/trace.go:171","msg":"trace[583182658] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1960; }","duration":"497.209109ms","start":"2026-04-18T22:14:38.723535Z","end":"2026-04-18T22:14:39.220744Z","steps":["trace[583182658] 'agreement among raft nodes before linearized reading' (duration: 497.022374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:14:39.22075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:14:38.728875Z","time spent":"491.868368ms","remote":"127.0.0.1:48006","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-04-18T22:14:39.220784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:14:38.723518Z","time spent":"497.25684ms","remote":"127.0.0.1:48284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-04-18T22:15:02.086357Z","caller":"traceutil/trace.go:171","msg":"trace[1535984197] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"109.443681ms","start":"2026-04-18T22:15:01.976882Z","end":"2026-04-18T22:15:02.086325Z","steps":["trace[1535984197] 'process raft request' (duration: 103.95931ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:15:02.34107Z","caller":"traceutil/trace.go:171","msg":"trace[1462994807] linearizableReadLoop","detail":"{readStateIndex:2185; appliedIndex:2184; }","duration":"165.724514ms","start":"2026-04-18T22:15:02.175318Z","end":"2026-04-18T22:15:02.341042Z","steps":["trace[1462994807] 'read index received' (duration: 149.617826ms)","trace[1462994807] 'applied index is now lower than readState.Index' (duration: 16.105518ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:15:02.341266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.918907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-18T22:15:02.341307Z","caller":"traceutil/trace.go:171","msg":"trace[1123426239] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2103; }","duration":"166.000829ms","start":"2026-04-18T22:15:02.175295Z","end":"2026-04-18T22:15:02.341296Z","steps":["trace[1123426239] 'agreement among raft nodes before linearized reading' (duration: 165.842446ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:15:02.34164Z","caller":"traceutil/trace.go:171","msg":"trace[1802679267] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"166.69073ms","start":"2026-04-18T22:15:02.174935Z","end":"2026-04-18T22:15:02.341626Z","steps":["trace[1802679267] 'process raft request' (duration: 149.990203ms)","trace[1802679267] 'compare' (duration: 15.999825ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:15:03.861233Z","caller":"traceutil/trace.go:171","msg":"trace[1607909279] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"326.039902ms","start":"2026-04-18T22:15:03.535164Z","end":"2026-04-18T22:15:03.861204Z","steps":["trace[1607909279] 'process raft request' (duration: 323.53306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:03.861528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:03.535145Z","time spent":"326.233085ms","remote":"127.0.0.1:48284","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-04-18T22:15:04.367852Z","caller":"traceutil/trace.go:171","msg":"trace[1050230969] linearizableReadLoop","detail":"{readStateIndex:2189; appliedIndex:2187; }","duration":"814.882797ms","start":"2026-04-18T22:15:03.552947Z","end":"2026-04-18T22:15:04.36783Z","steps":["trace[1050230969] 'read index received' (duration: 305.740595ms)","trace[1050230969] 'applied index is now lower than readState.Index' (duration: 509.141322ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:15:04.367986Z","caller":"traceutil/trace.go:171","msg":"trace[213614373] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"831.203237ms","start":"2026-04-18T22:15:03.53677Z","end":"2026-04-18T22:15:04.367974Z","steps":["trace[213614373] 'process raft request' (duration: 771.362912ms)","trace[213614373] 'compare' (duration: 59.406618ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:15:04.368084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:03.536753Z","time spent":"831.258308ms","remote":"127.0.0.1:48284","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-04-18T22:15:04.368156Z","caller":"traceutil/trace.go:171","msg":"trace[1821028568] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"269.715249ms","start":"2026-04-18T22:15:04.098419Z","end":"2026-04-18T22:15:04.368134Z","steps":["trace[1821028568] 'process raft request' (duration: 269.633838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:04.368308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.366014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T22:15:04.368344Z","caller":"traceutil/trace.go:171","msg":"trace[1720112024] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:2110; }","duration":"815.422646ms","start":"2026-04-18T22:15:03.552909Z","end":"2026-04-18T22:15:04.368332Z","steps":["trace[1720112024] 'agreement among raft nodes before linearized reading' (duration: 815.345655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:04.368375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:03.552898Z","time spent":"815.469156ms","remote":"127.0.0.1:48162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":30,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-04-18T22:15:04.368432Z","caller":"traceutil/trace.go:171","msg":"trace[1940202536] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"431.841112ms","start":"2026-04-18T22:15:03.936578Z","end":"2026-04-18T22:15:04.368419Z","steps":["trace[1940202536] 'process raft request' (duration: 431.222953ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:15:04.368527Z","caller":"traceutil/trace.go:171","msg":"trace[1556326118] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"272.091689ms","start":"2026-04-18T22:15:04.096388Z","end":"2026-04-18T22:15:04.36848Z","steps":["trace[1556326118] 'process raft request' (duration: 271.603321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:04.368576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.541978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-18T22:15:04.368676Z","caller":"traceutil/trace.go:171","msg":"trace[1608278693] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2110; }","duration":"792.67057ms","start":"2026-04-18T22:15:03.575959Z","end":"2026-04-18T22:15:04.36863Z","steps":["trace[1608278693] 'agreement among raft nodes before linearized reading' (duration: 792.427166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:04.368719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:03.57594Z","time spent":"792.770093ms","remote":"127.0.0.1:56614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-18T22:15:04.368757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.550902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-18T22:15:04.368803Z","caller":"traceutil/trace.go:171","msg":"trace[607512470] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2110; }","duration":"245.622793ms","start":"2026-04-18T22:15:04.123167Z","end":"2026-04-18T22:15:04.36879Z","steps":["trace[607512470] 'agreement among raft nodes before linearized reading' (duration: 245.488041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:04.368888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.459715ms","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-04-18T22:15:04.368924Z","caller":"traceutil/trace.go:171","msg":"trace[193802768] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2110; }","duration":"498.558857ms","start":"2026-04-18T22:15:03.870355Z","end":"2026-04-18T22:15:04.368914Z","steps":["trace[193802768] 'agreement among raft nodes before linearized reading' (duration: 498.465095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:04.36895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:03.870338Z","time spent":"498.606237ms","remote":"127.0.0.1:48284","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-04-18T22:15:04.369051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.070246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:15:04.369106Z","caller":"traceutil/trace.go:171","msg":"trace[1209972507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2110; }","duration":"640.177158ms","start":"2026-04-18T22:15:03.728912Z","end":"2026-04-18T22:15:04.369089Z","steps":["trace[1209972507] 'agreement among raft nodes before linearized reading' (duration: 640.069237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:04.369155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:03.72889Z","time spent":"640.25355ms","remote":"127.0.0.1:48002","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-04-18T22:15:04.370105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:03.936544Z","time spent":"432.001166ms","remote":"127.0.0.1:48284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:15:53.441311Z","caller":"traceutil/trace.go:171","msg":"trace[1920663826] transaction","detail":"{read_only:false; response_revision:2481; number_of_response:1; }","duration":"109.500923ms","start":"2026-04-18T22:15:53.33179Z","end":"2026-04-18T22:15:53.441291Z","steps":["trace[1920663826] 'process raft request' (duration: 109.352751ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:16:26.354375Z","caller":"traceutil/trace.go:171","msg":"trace[122692013] transaction","detail":"{read_only:false; response_revision:2661; number_of_response:1; }","duration":"100.475515ms","start":"2026-04-18T22:16:26.253866Z","end":"2026-04-18T22:16:26.354342Z","steps":["trace[122692013] 'process raft request' (duration: 100.305363ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:16:26.641058Z","caller":"traceutil/trace.go:171","msg":"trace[919304726] transaction","detail":"{read_only:false; response_revision:2662; number_of_response:1; }","duration":"283.979088ms","start":"2026-04-18T22:16:26.35705Z","end":"2026-04-18T22:16:26.641029Z","steps":["trace[919304726] 'process raft request' (duration: 271.458586ms)","trace[919304726] 'compare' (duration: 12.38127ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:18:23.663699Z","caller":"traceutil/trace.go:171","msg":"trace[1071266674] transaction","detail":"{read_only:false; response_revision:3365; number_of_response:1; }","duration":"461.079995ms","start":"2026-04-18T22:18:23.202534Z","end":"2026-04-18T22:18:23.663614Z","steps":["trace[1071266674] 'process raft request' (duration: 441.64723ms)","trace[1071266674] 'compare' (duration: 19.039382ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:18:23.663919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:23.202504Z","time spent":"461.304201ms","remote":"127.0.0.1:48284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:18:23.685617Z","caller":"traceutil/trace.go:171","msg":"trace[1876201841] linearizableReadLoop","detail":"{readStateIndex:3495; appliedIndex:3493; }","duration":"410.348554ms","start":"2026-04-18T22:18:23.275245Z","end":"2026-04-18T22:18:23.685594Z","steps":["trace[1876201841] 'read index received' (duration: 368.954878ms)","trace[1876201841] 'applied index is now lower than readState.Index' (duration: 41.392656ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:18:23.68574Z","caller":"traceutil/trace.go:171","msg":"trace[752050730] transaction","detail":"{read_only:false; response_revision:3366; number_of_response:1; }","duration":"427.981218ms","start":"2026-04-18T22:18:23.257748Z","end":"2026-04-18T22:18:23.685729Z","steps":["trace[752050730] 'process raft request' (duration: 427.72727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:23.685929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.671256ms","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-04-18T22:18:23.68597Z","caller":"traceutil/trace.go:171","msg":"trace[624728088] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3366; }","duration":"410.767959ms","start":"2026-04-18T22:18:23.275191Z","end":"2026-04-18T22:18:23.685959Z","steps":["trace[624728088] 'agreement among raft nodes before linearized reading' (duration: 410.609123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:23.685975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.189358ms","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-04-18T22:18:23.686002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:23.275177Z","time spent":"410.81749ms","remote":"127.0.0.1:48284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-18T22:18:23.686041Z","caller":"traceutil/trace.go:171","msg":"trace[776022764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3366; }","duration":"275.331403ms","start":"2026-04-18T22:18:23.410696Z","end":"2026-04-18T22:18:23.686027Z","steps":["trace[776022764] 'agreement among raft nodes before linearized reading' (duration: 275.134045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:23.686058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.520742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" ","response":"range_response_count:1 size:1229"} {"level":"warn","ts":"2026-04-18T22:18:23.686111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.869312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-04-18T22:18:23.686143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.913606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-18T22:18:23.686212Z","caller":"traceutil/trace.go:171","msg":"trace[940957972] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3366; }","duration":"155.032077ms","start":"2026-04-18T22:18:23.531155Z","end":"2026-04-18T22:18:23.686187Z","steps":["trace[940957972] 'agreement among raft nodes before linearized reading' (duration: 154.707896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:23.686088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.42016ms","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-04-18T22:18:23.686263Z","caller":"traceutil/trace.go:171","msg":"trace[1203204576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3366; }","duration":"279.634017ms","start":"2026-04-18T22:18:23.406619Z","end":"2026-04-18T22:18:23.686253Z","steps":["trace[1203204576] 'agreement among raft nodes before linearized reading' (duration: 279.285466ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:23.686226Z","caller":"traceutil/trace.go:171","msg":"trace[1795881605] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret; range_end:; response_count:1; response_revision:3366; }","duration":"405.704568ms","start":"2026-04-18T22:18:23.280454Z","end":"2026-04-18T22:18:23.686158Z","steps":["trace[1795881605] 'agreement among raft nodes before linearized reading' (duration: 405.379347ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:23.686309Z","caller":"traceutil/trace.go:171","msg":"trace[354408266] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3366; }","duration":"301.112702ms","start":"2026-04-18T22:18:23.385163Z","end":"2026-04-18T22:18:23.686276Z","steps":["trace[354408266] 'agreement among raft nodes before linearized reading' (duration: 300.73596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:23.686215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:23.25772Z","time spent":"428.399663ms","remote":"127.0.0.1:48284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:18:23.686372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:23.280404Z","time spent":"405.953467ms","remote":"127.0.0.1:60850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":1252,"request content":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" "} {"level":"warn","ts":"2026-04-18T22:18:23.687012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:23.385139Z","time spent":"301.288717ms","remote":"127.0.0.1:48284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-18T22:18:40.069784Z","caller":"traceutil/trace.go:171","msg":"trace[408641689] transaction","detail":"{read_only:false; response_revision:3456; number_of_response:1; }","duration":"150.574471ms","start":"2026-04-18T22:18:39.919185Z","end":"2026-04-18T22:18:40.069759Z","steps":["trace[408641689] 'process raft request' (duration: 53.076603ms)","trace[408641689] 'compare' (duration: 97.326972ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:18:40.070071Z","caller":"traceutil/trace.go:171","msg":"trace[912211490] transaction","detail":"{read_only:false; response_revision:3457; number_of_response:1; }","duration":"150.720415ms","start":"2026-04-18T22:18:39.919277Z","end":"2026-04-18T22:18:40.069997Z","steps":["trace[912211490] 'process raft request' (duration: 150.441357ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:00.554311Z","caller":"traceutil/trace.go:171","msg":"trace[2050646722] linearizableReadLoop","detail":"{readStateIndex:4496; appliedIndex:4495; }","duration":"163.942391ms","start":"2026-04-18T22:20:00.39035Z","end":"2026-04-18T22:20:00.554292Z","steps":["trace[2050646722] 'read index received' (duration: 143.316398ms)","trace[2050646722] 'applied index is now lower than readState.Index' (duration: 20.624863ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:00.55433Z","caller":"traceutil/trace.go:171","msg":"trace[1667820899] transaction","detail":"{read_only:false; response_revision:4327; number_of_response:1; }","duration":"186.755277ms","start":"2026-04-18T22:20:00.367546Z","end":"2026-04-18T22:20:00.554301Z","steps":["trace[1667820899] 'process raft request' (duration: 166.070952ms)","trace[1667820899] 'compare' (duration: 20.531011ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:20:00.55464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.283009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:20:00.554735Z","caller":"traceutil/trace.go:171","msg":"trace[1218848590] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:4327; }","duration":"164.417072ms","start":"2026-04-18T22:20:00.390306Z","end":"2026-04-18T22:20:00.554723Z","steps":["trace[1218848590] 'agreement among raft nodes before linearized reading' (duration: 164.265128ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:00.555886Z","caller":"traceutil/trace.go:171","msg":"trace[1558747374] transaction","detail":"{read_only:false; response_revision:4328; number_of_response:1; }","duration":"137.708984ms","start":"2026-04-18T22:20:00.418131Z","end":"2026-04-18T22:20:00.55584Z","steps":["trace[1558747374] 'process raft request' (duration: 137.577061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:00.556057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.501254ms","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-04-18T22:20:00.556083Z","caller":"traceutil/trace.go:171","msg":"trace[271328531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4330; }","duration":"131.553887ms","start":"2026-04-18T22:20:00.42452Z","end":"2026-04-18T22:20:00.556074Z","steps":["trace[271328531] 'agreement among raft nodes before linearized reading' (duration: 131.504995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:00.555923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.755285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-18T22:20:00.556185Z","caller":"traceutil/trace.go:171","msg":"trace[1108979137] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4330; }","duration":"133.062023ms","start":"2026-04-18T22:20:00.423111Z","end":"2026-04-18T22:20:00.556173Z","steps":["trace[1108979137] 'agreement among raft nodes before linearized reading' (duration: 132.719584ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:06.375243Z","caller":"traceutil/trace.go:171","msg":"trace[1319670041] transaction","detail":"{read_only:false; response_revision:4496; number_of_response:1; }","duration":"121.251302ms","start":"2026-04-18T22:20:06.253968Z","end":"2026-04-18T22:20:06.375219Z","steps":["trace[1319670041] 'process raft request' (duration: 100.647418ms)","trace[1319670041] 'compare' (duration: 20.43976ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:13.961625Z","caller":"traceutil/trace.go:171","msg":"trace[2133868116] linearizableReadLoop","detail":"{readStateIndex:4846; appliedIndex:4845; }","duration":"232.492847ms","start":"2026-04-18T22:20:13.729108Z","end":"2026-04-18T22:20:13.961601Z","steps":["trace[2133868116] 'read index received' (duration: 232.328223ms)","trace[2133868116] 'applied index is now lower than readState.Index' (duration: 163.634µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:13.96193Z","caller":"traceutil/trace.go:171","msg":"trace[1031681250] transaction","detail":"{read_only:false; response_revision:4668; number_of_response:1; }","duration":"263.450731ms","start":"2026-04-18T22:20:13.698466Z","end":"2026-04-18T22:20:13.961917Z","steps":["trace[1031681250] 'process raft request' (duration: 262.95437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:13.962098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.958906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:20:13.962296Z","caller":"traceutil/trace.go:171","msg":"trace[1243179914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4668; }","duration":"233.202953ms","start":"2026-04-18T22:20:13.729068Z","end":"2026-04-18T22:20:13.962271Z","steps":["trace[1243179914] 'agreement among raft nodes before linearized reading' (duration: 232.840104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:13.989994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.812292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-312e6743-687b-43f9-aa78-1cb962dacd4f\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-18T22:20:13.990091Z","caller":"traceutil/trace.go:171","msg":"trace[1831586418] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-312e6743-687b-43f9-aa78-1cb962dacd4f; range_end:; response_count:1; response_revision:4669; }","duration":"254.944285ms","start":"2026-04-18T22:20:13.735125Z","end":"2026-04-18T22:20:13.99007Z","steps":["trace[1831586418] 'agreement among raft nodes before linearized reading' (duration: 254.649759ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:14.323262Z","caller":"traceutil/trace.go:171","msg":"trace[1705497570] linearizableReadLoop","detail":"{readStateIndex:4848; appliedIndex:4847; }","duration":"215.01856ms","start":"2026-04-18T22:20:14.10821Z","end":"2026-04-18T22:20:14.323229Z","steps":["trace[1705497570] 'read index received' (duration: 214.727594ms)","trace[1705497570] 'applied index is now lower than readState.Index' (duration: 289.276µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:20:14.323499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.254657ms","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-04-18T22:20:14.323588Z","caller":"traceutil/trace.go:171","msg":"trace[1540687623] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:4670; }","duration":"215.434191ms","start":"2026-04-18T22:20:14.108141Z","end":"2026-04-18T22:20:14.323575Z","steps":["trace[1540687623] 'agreement among raft nodes before linearized reading' (duration: 215.233845ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:14.32357Z","caller":"traceutil/trace.go:171","msg":"trace[1739532982] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"325.723151ms","start":"2026-04-18T22:20:13.997813Z","end":"2026-04-18T22:20:14.323536Z","steps":["trace[1739532982] 'process raft request' (duration: 325.191368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:14.323735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:13.997802Z","time spent":"325.839743ms","remote":"127.0.0.1:48190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:20:14.351444Z","caller":"traceutil/trace.go:171","msg":"trace[1766876175] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"169.39128ms","start":"2026-04-18T22:20:14.182027Z","end":"2026-04-18T22:20:14.351418Z","steps":["trace[1766876175] 'process raft request' (duration: 169.150064ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:14.351849Z","caller":"traceutil/trace.go:171","msg":"trace[1260180857] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"144.298982ms","start":"2026-04-18T22:20:14.20753Z","end":"2026-04-18T22:20:14.351829Z","steps":["trace[1260180857] 'process raft request' (duration: 143.828641ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:14.352173Z","caller":"traceutil/trace.go:171","msg":"trace[1626941003] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"135.93418ms","start":"2026-04-18T22:20:14.21623Z","end":"2026-04-18T22:20:14.352164Z","steps":["trace[1626941003] 'process raft request' (duration: 135.51297ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:15.027294Z","caller":"traceutil/trace.go:171","msg":"trace[1097407178] transaction","detail":"{read_only:false; response_revision:4682; number_of_response:1; }","duration":"110.885182ms","start":"2026-04-18T22:20:14.916382Z","end":"2026-04-18T22:20:15.027267Z","steps":["trace[1097407178] 'process raft request' (duration: 110.692508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:16.899864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.870854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:20:16.90007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.177433ms","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-04-18T22:20:16.900149Z","caller":"traceutil/trace.go:171","msg":"trace[663126217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4707; }","duration":"158.289525ms","start":"2026-04-18T22:20:16.741843Z","end":"2026-04-18T22:20:16.900132Z","steps":["trace[663126217] 'range keys from in-memory index tree' (duration: 157.996709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:16.900144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.125629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-18T22:20:16.900194Z","caller":"traceutil/trace.go:171","msg":"trace[1636321466] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4707; }","duration":"171.216531ms","start":"2026-04-18T22:20:16.728965Z","end":"2026-04-18T22:20:16.900182Z","steps":["trace[1636321466] 'range keys from in-memory index tree' (duration: 170.956135ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:16.900128Z","caller":"traceutil/trace.go:171","msg":"trace[846853002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4707; }","duration":"171.170741ms","start":"2026-04-18T22:20:16.7289Z","end":"2026-04-18T22:20:16.90007Z","steps":["trace[846853002] 'range keys from in-memory index tree' (duration: 170.831913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:16.901959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.607015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-18T22:20:16.90226Z","caller":"traceutil/trace.go:171","msg":"trace[1202057690] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4707; }","duration":"218.379902ms","start":"2026-04-18T22:20:16.68386Z","end":"2026-04-18T22:20:16.90224Z","steps":["trace[1202057690] 'range keys from in-memory index tree' (duration: 216.798996ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:19.850671Z","caller":"traceutil/trace.go:171","msg":"trace[652647852] linearizableReadLoop","detail":"{readStateIndex:4932; appliedIndex:4931; }","duration":"122.132147ms","start":"2026-04-18T22:20:19.728521Z","end":"2026-04-18T22:20:19.850653Z","steps":["trace[652647852] 'read index received' (duration: 115.962266ms)","trace[652647852] 'applied index is now lower than readState.Index' (duration: 6.169231ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:20:19.850789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.249569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:20:19.850813Z","caller":"traceutil/trace.go:171","msg":"trace[2023440040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4752; }","duration":"122.3182ms","start":"2026-04-18T22:20:19.728488Z","end":"2026-04-18T22:20:19.850806Z","steps":["trace[2023440040] 'agreement among raft nodes before linearized reading' (duration: 122.242459ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:19.850765Z","caller":"traceutil/trace.go:171","msg":"trace[1820800306] transaction","detail":"{read_only:false; response_revision:4752; number_of_response:1; }","duration":"141.748443ms","start":"2026-04-18T22:20:19.708946Z","end":"2026-04-18T22:20:19.850695Z","steps":["trace[1820800306] 'process raft request' (duration: 135.623543ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:22.136968Z","caller":"traceutil/trace.go:171","msg":"trace[2020567547] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"153.494283ms","start":"2026-04-18T22:20:21.983451Z","end":"2026-04-18T22:20:22.136945Z","steps":["trace[2020567547] 'process raft request' (duration: 153.3623ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:22.145386Z","caller":"traceutil/trace.go:171","msg":"trace[462070494] transaction","detail":"{read_only:false; response_revision:4796; number_of_response:1; }","duration":"154.699191ms","start":"2026-04-18T22:20:21.990667Z","end":"2026-04-18T22:20:22.145366Z","steps":["trace[462070494] 'process raft request' (duration: 154.565478ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:24.593605Z","caller":"traceutil/trace.go:171","msg":"trace[1152958488] transaction","detail":"{read_only:false; response_revision:4813; number_of_response:1; }","duration":"144.237747ms","start":"2026-04-18T22:20:24.449335Z","end":"2026-04-18T22:20:24.593572Z","steps":["trace[1152958488] 'process raft request' (duration: 144.029912ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:25.061232Z","caller":"traceutil/trace.go:171","msg":"trace[1879558961] linearizableReadLoop","detail":"{readStateIndex:5002; appliedIndex:5001; }","duration":"113.919643ms","start":"2026-04-18T22:20:24.947291Z","end":"2026-04-18T22:20:25.06121Z","steps":["trace[1879558961] 'read index received' (duration: 113.866601ms)","trace[1879558961] 'applied index is now lower than readState.Index' (duration: 51.992µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:20:25.061438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.144499ms","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-04-18T22:20:25.061478Z","caller":"traceutil/trace.go:171","msg":"trace[278545575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4819; }","duration":"114.232341ms","start":"2026-04-18T22:20:24.947237Z","end":"2026-04-18T22:20:25.061469Z","steps":["trace[278545575] 'agreement among raft nodes before linearized reading' (duration: 114.094997ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:25.061417Z","caller":"traceutil/trace.go:171","msg":"trace[737188155] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"137.4038ms","start":"2026-04-18T22:20:24.923879Z","end":"2026-04-18T22:20:25.061283Z","steps":["trace[737188155] 'process raft request' (duration: 137.193026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:25.061543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.646255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-18T22:20:25.061598Z","caller":"traceutil/trace.go:171","msg":"trace[2013679981] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4819; }","duration":"112.712846ms","start":"2026-04-18T22:20:24.948872Z","end":"2026-04-18T22:20:25.061585Z","steps":["trace[2013679981] 'agreement among raft nodes before linearized reading' (duration: 112.528233ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:27.910124Z","caller":"traceutil/trace.go:171","msg":"trace[52864283] linearizableReadLoop","detail":"{readStateIndex:5025; appliedIndex:5024; }","duration":"189.035641ms","start":"2026-04-18T22:20:27.721066Z","end":"2026-04-18T22:20:27.910102Z","steps":["trace[52864283] 'read index received' (duration: 188.765205ms)","trace[52864283] 'applied index is now lower than readState.Index' (duration: 269.536µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:27.910298Z","caller":"traceutil/trace.go:171","msg":"trace[120711762] transaction","detail":"{read_only:false; response_revision:4841; number_of_response:1; }","duration":"326.80591ms","start":"2026-04-18T22:20:27.583481Z","end":"2026-04-18T22:20:27.910286Z","steps":["trace[120711762] 'process raft request' (duration: 326.432901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:27.91055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:27.583468Z","time spent":"326.891642ms","remote":"127.0.0.1:48284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:20:27.910604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.573879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:20:27.910675Z","caller":"traceutil/trace.go:171","msg":"trace[322813953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4841; }","duration":"174.689111ms","start":"2026-04-18T22:20:27.735973Z","end":"2026-04-18T22:20:27.910663Z","steps":["trace[322813953] 'agreement among raft nodes before linearized reading' (duration: 174.404835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:27.910681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.589053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-04-18T22:20:27.910762Z","caller":"traceutil/trace.go:171","msg":"trace[2115553264] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4841; }","duration":"189.718017ms","start":"2026-04-18T22:20:27.721022Z","end":"2026-04-18T22:20:27.91074Z","steps":["trace[2115553264] 'agreement among raft nodes before linearized reading' (duration: 189.40787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:28.56635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.325598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:20:28.56645Z","caller":"traceutil/trace.go:171","msg":"trace[1965065363] linearizableReadLoop","detail":"{readStateIndex:5026; appliedIndex:5025; }","duration":"644.911576ms","start":"2026-04-18T22:20:27.921524Z","end":"2026-04-18T22:20:28.566435Z","steps":["trace[1965065363] 'read index received' (duration: 33.344502ms)","trace[1965065363] 'applied index is now lower than readState.Index' (duration: 611.566034ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:28.566526Z","caller":"traceutil/trace.go:171","msg":"trace[1090065516] transaction","detail":"{read_only:false; response_revision:4842; number_of_response:1; }","duration":"648.505276ms","start":"2026-04-18T22:20:27.917988Z","end":"2026-04-18T22:20:28.566493Z","steps":["trace[1090065516] 'process raft request' (duration: 36.982944ms)","trace[1090065516] 'compare' (duration: 610.684774ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:20:28.566561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:27.917968Z","time spent":"648.575698ms","remote":"127.0.0.1:48086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":794,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T22:20:28.569006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.471634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-04-18T22:20:28.569053Z","caller":"traceutil/trace.go:171","msg":"trace[801116363] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4842; }","duration":"647.541405ms","start":"2026-04-18T22:20:27.9215Z","end":"2026-04-18T22:20:28.569042Z","steps":["trace[801116363] 'agreement among raft nodes before linearized reading' (duration: 645.021609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:28.569087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:27.921493Z","time spent":"647.585856ms","remote":"127.0.0.1:48202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1288,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" "} {"level":"info","ts":"2026-04-18T22:20:28.590318Z","caller":"traceutil/trace.go:171","msg":"trace[1751658623] transaction","detail":"{read_only:false; response_revision:4843; number_of_response:1; }","duration":"436.389947ms","start":"2026-04-18T22:20:28.153893Z","end":"2026-04-18T22:20:28.590283Z","steps":["trace[1751658623] 'process raft request' (duration: 436.176213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:28.59043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.347271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-66849568cf-vvk5n\" ","response":"range_response_count:1 size:11848"} {"level":"info","ts":"2026-04-18T22:20:28.590544Z","caller":"traceutil/trace.go:171","msg":"trace[438612554] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-66849568cf-vvk5n; range_end:; response_count:1; response_revision:4843; }","duration":"240.505174ms","start":"2026-04-18T22:20:28.35002Z","end":"2026-04-18T22:20:28.590525Z","steps":["trace[438612554] 'agreement among raft nodes before linearized reading' (duration: 240.267219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:28.590493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:28.153863Z","time spent":"436.540061ms","remote":"127.0.0.1:48284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:20:28.590966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.946752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-18T22:20:28.591022Z","caller":"traceutil/trace.go:171","msg":"trace[2007927747] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4843; }","duration":"555.628128ms","start":"2026-04-18T22:20:28.035367Z","end":"2026-04-18T22:20:28.590995Z","steps":["trace[2007927747] 'agreement among raft nodes before linearized reading' (duration: 554.881451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:28.591057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:28.035347Z","time spent":"555.70066ms","remote":"127.0.0.1:48162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-04-18T22:20:28.732154Z","caller":"traceutil/trace.go:171","msg":"trace[1873938382] transaction","detail":"{read_only:false; response_revision:4845; number_of_response:1; }","duration":"130.326816ms","start":"2026-04-18T22:20:28.601788Z","end":"2026-04-18T22:20:28.732115Z","steps":["trace[1873938382] 'process raft request' (duration: 109.478793ms)","trace[1873938382] 'compare' (duration: 20.608217ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:28.732246Z","caller":"traceutil/trace.go:171","msg":"trace[2095145397] transaction","detail":"{read_only:false; response_revision:4846; number_of_response:1; }","duration":"124.148429ms","start":"2026-04-18T22:20:28.608071Z","end":"2026-04-18T22:20:28.73222Z","steps":["trace[2095145397] 'process raft request' (duration: 123.960555ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:28.732921Z","caller":"traceutil/trace.go:171","msg":"trace[926840307] transaction","detail":"{read_only:false; response_revision:4847; number_of_response:1; }","duration":"122.198446ms","start":"2026-04-18T22:20:28.610706Z","end":"2026-04-18T22:20:28.732905Z","steps":["trace[926840307] 'process raft request' (duration: 121.383828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:20:28.734087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.397075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-04-18T22:20:28.734176Z","caller":"traceutil/trace.go:171","msg":"trace[1056176502] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4847; }","duration":"138.510278ms","start":"2026-04-18T22:20:28.595654Z","end":"2026-04-18T22:20:28.734164Z","steps":["trace[1056176502] 'agreement among raft nodes before linearized reading' (duration: 138.306783ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:28.733917Z","caller":"traceutil/trace.go:171","msg":"trace[1477971472] linearizableReadLoop","detail":"{readStateIndex:5031; appliedIndex:5028; }","duration":"105.939495ms","start":"2026-04-18T22:20:28.626788Z","end":"2026-04-18T22:20:28.732728Z","steps":["trace[1477971472] 'read index received' (duration: 84.389286ms)","trace[1477971472] 'applied index is now lower than readState.Index' (duration: 21.549199ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:20:28.735084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.409143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18a7938d376d3198\" ","response":"range_response_count:1 size:740"} {"level":"info","ts":"2026-04-18T22:20:28.735125Z","caller":"traceutil/trace.go:171","msg":"trace[1479190942] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18a7938d376d3198; range_end:; response_count:1; response_revision:4847; }","duration":"105.475535ms","start":"2026-04-18T22:20:28.629637Z","end":"2026-04-18T22:20:28.735113Z","steps":["trace[1479190942] 'agreement among raft nodes before linearized reading' (duration: 105.376523ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:03.303959Z","caller":"traceutil/trace.go:171","msg":"trace[113915243] transaction","detail":"{read_only:false; response_revision:5146; number_of_response:1; }","duration":"119.681912ms","start":"2026-04-18T22:21:03.184251Z","end":"2026-04-18T22:21:03.303933Z","steps":["trace[113915243] 'process raft request' (duration: 45.917494ms)","trace[113915243] 'compare' (duration: 73.526042ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:21:03.304097Z","caller":"traceutil/trace.go:171","msg":"trace[550188418] transaction","detail":"{read_only:false; response_revision:5147; number_of_response:1; }","duration":"118.652381ms","start":"2026-04-18T22:21:03.185396Z","end":"2026-04-18T22:21:03.304048Z","steps":["trace[550188418] 'process raft request' (duration: 118.447216ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:03.304535Z","caller":"traceutil/trace.go:171","msg":"trace[1478969481] transaction","detail":"{read_only:false; response_revision:5148; number_of_response:1; }","duration":"118.763332ms","start":"2026-04-18T22:21:03.185747Z","end":"2026-04-18T22:21:03.30451Z","steps":["trace[1478969481] 'process raft request' (duration: 118.14919ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:03.305009Z","caller":"traceutil/trace.go:171","msg":"trace[1113293497] linearizableReadLoop","detail":"{readStateIndex:5341; appliedIndex:5338; }","duration":"107.106093ms","start":"2026-04-18T22:21:03.197884Z","end":"2026-04-18T22:21:03.30499Z","steps":["trace[1113293497] 'read index received' (duration: 32.300594ms)","trace[1113293497] 'applied index is now lower than readState.Index' (duration: 74.804499ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:21:03.305247Z","caller":"traceutil/trace.go:171","msg":"trace[841730641] transaction","detail":"{read_only:false; response_revision:5149; number_of_response:1; }","duration":"100.501457ms","start":"2026-04-18T22:21:03.204734Z","end":"2026-04-18T22:21:03.305235Z","steps":["trace[841730641] 'process raft request' (duration: 99.221661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:21:03.305488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.588263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-04-18T22:21:03.30552Z","caller":"traceutil/trace.go:171","msg":"trace[492174437] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:5150; }","duration":"107.631424ms","start":"2026-04-18T22:21:03.197878Z","end":"2026-04-18T22:21:03.305509Z","steps":["trace[492174437] 'agreement among raft nodes before linearized reading' (duration: 107.517982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:21:03.524427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.051839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:21:03.524531Z","caller":"traceutil/trace.go:171","msg":"trace[1341290832] linearizableReadLoop","detail":"{readStateIndex:5345; appliedIndex:5344; }","duration":"139.06114ms","start":"2026-04-18T22:21:03.385456Z","end":"2026-04-18T22:21:03.524517Z","steps":["trace[1341290832] 'read index received' (duration: 34.791296ms)","trace[1341290832] 'applied index is now lower than readState.Index' (duration: 104.268684ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:21:03.524742Z","caller":"traceutil/trace.go:171","msg":"trace[1756393406] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"193.123104ms","start":"2026-04-18T22:21:03.331608Z","end":"2026-04-18T22:21:03.524731Z","steps":["trace[1756393406] 'process raft request' (duration: 88.685226ms)","trace[1756393406] 'compare' (duration: 103.804244ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:21:03.525039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.58024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61971"} {"level":"info","ts":"2026-04-18T22:21:03.525067Z","caller":"traceutil/trace.go:171","msg":"trace[1853572525] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:5152; }","duration":"139.649182ms","start":"2026-04-18T22:21:03.385411Z","end":"2026-04-18T22:21:03.52506Z","steps":["trace[1853572525] 'agreement among raft nodes before linearized reading' (duration: 139.473038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:21:03.525267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.558021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-18T22:21:03.525302Z","caller":"traceutil/trace.go:171","msg":"trace[577576480] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5152; }","duration":"139.609421ms","start":"2026-04-18T22:21:03.385685Z","end":"2026-04-18T22:21:03.525294Z","steps":["trace[577576480] 'agreement among raft nodes before linearized reading' (duration: 139.52464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:21:03.525652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.564028ms","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-04-18T22:21:03.525677Z","caller":"traceutil/trace.go:171","msg":"trace[1539394243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5152; }","duration":"118.612ms","start":"2026-04-18T22:21:03.407059Z","end":"2026-04-18T22:21:03.525671Z","steps":["trace[1539394243] 'agreement among raft nodes before linearized reading' (duration: 118.552668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:21:03.525812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.738272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-18T22:21:03.525834Z","caller":"traceutil/trace.go:171","msg":"trace[872156546] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5152; }","duration":"118.787694ms","start":"2026-04-18T22:21:03.407041Z","end":"2026-04-18T22:21:03.525829Z","steps":["trace[872156546] 'agreement among raft nodes before linearized reading' (duration: 118.726881ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:08.231125Z","caller":"traceutil/trace.go:171","msg":"trace[826491056] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"119.077775ms","start":"2026-04-18T22:21:08.112021Z","end":"2026-04-18T22:21:08.231099Z","steps":["trace[826491056] 'process raft request' (duration: 118.905922ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:49.89088Z","caller":"traceutil/trace.go:171","msg":"trace[260838721] transaction","detail":"{read_only:false; response_revision:5494; number_of_response:1; }","duration":"114.220318ms","start":"2026-04-18T22:21:49.776634Z","end":"2026-04-18T22:21:49.890855Z","steps":["trace[260838721] 'process raft request' (duration: 114.091915ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:49.901386Z","caller":"traceutil/trace.go:171","msg":"trace[449595362] transaction","detail":"{read_only:false; response_revision:5495; number_of_response:1; }","duration":"123.165707ms","start":"2026-04-18T22:21:49.778202Z","end":"2026-04-18T22:21:49.901368Z","steps":["trace[449595362] 'process raft request' (duration: 123.059765ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:21:53.6086Z","caller":"traceutil/trace.go:171","msg":"trace[1654682584] transaction","detail":"{read_only:false; response_revision:5512; number_of_response:1; }","duration":"115.964248ms","start":"2026-04-18T22:21:53.492614Z","end":"2026-04-18T22:21:53.608578Z","steps":["trace[1654682584] 'process raft request' (duration: 82.213602ms)","trace[1654682584] 'compare' (duration: 33.669205ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:22:09.251893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2890} {"level":"info","ts":"2026-04-18T22:22:09.325167Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2890,"took":"71.676123ms","hash":3328645216,"current-db-size-bytes":54550528,"current-db-size":"55 MB","current-db-size-in-use-bytes":29364224,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-18T22:22:09.32523Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3328645216,"revision":2890,"compact-revision":-1} {"level":"info","ts":"2026-04-18T22:24:02.699225Z","caller":"traceutil/trace.go:171","msg":"trace[1968194070] linearizableReadLoop","detail":"{readStateIndex:6627; appliedIndex:6625; }","duration":"143.998872ms","start":"2026-04-18T22:24:02.555206Z","end":"2026-04-18T22:24:02.699204Z","steps":["trace[1968194070] 'read index received' (duration: 28.323621ms)","trace[1968194070] 'applied index is now lower than readState.Index' (duration: 115.674421ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:24:02.699329Z","caller":"traceutil/trace.go:171","msg":"trace[1688829024] transaction","detail":"{read_only:false; response_revision:6391; number_of_response:1; }","duration":"147.988589ms","start":"2026-04-18T22:24:02.551312Z","end":"2026-04-18T22:24:02.699301Z","steps":["trace[1688829024] 'process raft request' (duration: 147.748575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:24:02.699488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.933841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-18T22:24:02.699528Z","caller":"traceutil/trace.go:171","msg":"trace[1952503867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6391; }","duration":"141.010842ms","start":"2026-04-18T22:24:02.558511Z","end":"2026-04-18T22:24:02.699521Z","steps":["trace[1952503867] 'agreement among raft nodes before linearized reading' (duration: 140.8844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:24:02.699599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.378139ms","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-04-18T22:24:02.699652Z","caller":"traceutil/trace.go:171","msg":"trace[1066919552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6391; }","duration":"144.460171ms","start":"2026-04-18T22:24:02.555179Z","end":"2026-04-18T22:24:02.699639Z","steps":["trace[1066919552] 'agreement among raft nodes before linearized reading' (duration: 144.141775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:24:02.699736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.828664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12209"} {"level":"warn","ts":"2026-04-18T22:24:02.699876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.763033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:24:02.69995Z","caller":"traceutil/trace.go:171","msg":"trace[60110302] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:0; response_revision:6391; }","duration":"130.886714ms","start":"2026-04-18T22:24:02.569048Z","end":"2026-04-18T22:24:02.699935Z","steps":["trace[60110302] 'agreement among raft nodes before linearized reading' (duration: 130.421276ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:24:02.7006Z","caller":"traceutil/trace.go:171","msg":"trace[1216289005] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6391; }","duration":"128.054348ms","start":"2026-04-18T22:24:02.571856Z","end":"2026-04-18T22:24:02.69991Z","steps":["trace[1216289005] 'agreement among raft nodes before linearized reading' (duration: 127.58164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:24:18.23719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.350961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"warn","ts":"2026-04-18T22:24:18.237283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.67216ms","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-04-18T22:24:18.237308Z","caller":"traceutil/trace.go:171","msg":"trace[1586218786] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:6492; }","duration":"151.500223ms","start":"2026-04-18T22:24:18.085783Z","end":"2026-04-18T22:24:18.237284Z","steps":["trace[1586218786] 'range keys from in-memory index tree' (duration: 151.161989ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:24:18.237344Z","caller":"traceutil/trace.go:171","msg":"trace[988391952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6492; }","duration":"130.811224ms","start":"2026-04-18T22:24:18.106517Z","end":"2026-04-18T22:24:18.237328Z","steps":["trace[988391952] 'range keys from in-memory index tree' (duration: 130.319345ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:24:18.237315Z","caller":"traceutil/trace.go:171","msg":"trace[736005176] transaction","detail":"{read_only:false; response_revision:6493; number_of_response:1; }","duration":"127.024981ms","start":"2026-04-18T22:24:18.110225Z","end":"2026-04-18T22:24:18.23725Z","steps":["trace[736005176] 'process raft request' (duration: 109.670526ms)","trace[736005176] 'compare' (duration: 17.05025ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:24:18.324872Z","caller":"traceutil/trace.go:171","msg":"trace[1206423731] transaction","detail":"{read_only:false; response_revision:6494; number_of_response:1; }","duration":"114.18148ms","start":"2026-04-18T22:24:18.210659Z","end":"2026-04-18T22:24:18.324841Z","steps":["trace[1206423731] 'process raft request' (duration: 114.011017ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:24:18.474916Z","caller":"traceutil/trace.go:171","msg":"trace[386624055] transaction","detail":"{read_only:false; response_revision:6495; number_of_response:1; }","duration":"231.731394ms","start":"2026-04-18T22:24:18.243163Z","end":"2026-04-18T22:24:18.474894Z","steps":["trace[386624055] 'process raft request' (duration: 221.687919ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:24:41.164364Z","caller":"traceutil/trace.go:171","msg":"trace[842052983] transaction","detail":"{read_only:false; response_revision:6668; number_of_response:1; }","duration":"116.750429ms","start":"2026-04-18T22:24:41.047574Z","end":"2026-04-18T22:24:41.164325Z","steps":["trace[842052983] 'process raft request' (duration: 72.55436ms)","trace[842052983] 'compare' (duration: 43.880014ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:27:09.25849Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5643} {"level":"info","ts":"2026-04-18T22:27:09.355504Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5643,"took":"95.871602ms","hash":1981731822,"current-db-size-bytes":54550528,"current-db-size":"55 MB","current-db-size-in-use-bytes":18231296,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-18T22:27:09.355574Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1981731822,"revision":5643,"compact-revision":2890}