{"level":"warn","ts":"2026-04-08T00:33:47.515235Z","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-08T00:33:47.515321Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.141: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.141:2380","--initial-cluster=instance=https://199.204.45.141:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.141:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.141: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-08T00:33:47.515384Z","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-08T00:33:47.515401Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-04-08T00:33:47.515417Z","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-08T00:33:47.51629Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"]} {"level":"info","ts":"2026-04-08T00:33:47.516369Z","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":16,"max-cpu-available":16,"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.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.141: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-08T00:33:47.521003Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.366909ms"} {"level":"info","ts":"2026-04-08T00:33:47.528353Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9ef3b4f68930dc2","cluster-id":"b19a5957e6e65747"} {"level":"info","ts":"2026-04-08T00:33:47.528448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T00:33:47.528483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 0"} {"level":"info","ts":"2026-04-08T00:33:47.5285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9ef3b4f68930dc2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T00:33:47.528597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 1"} {"level":"info","ts":"2026-04-08T00:33:47.528714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"warn","ts":"2026-04-08T00:33:47.534266Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T00:33:47.536185Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T00:33:47.537396Z","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-08T00:33:47.541774Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9ef3b4f68930dc2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T00:33:47.541973Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9ef3b4f68930dc2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T00:33:47.542177Z","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-08T00:33:47.542461Z","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-08T00:33:47.542483Z","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-08T00:33:47.545592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"info","ts":"2026-04-08T00:33:47.546432Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","added-peer-id":"9ef3b4f68930dc2","added-peer-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-04-08T00:33:47.548833Z","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-08T00:33:47.548949Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-04-08T00:33:47.549034Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-04-08T00:33:47.549145Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9ef3b4f68930dc2","initial-advertise-peer-urls":["https://199.204.45.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T00:33:47.5492Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T00:33:48.12976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T00:33:48.129828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T00:33:48.129852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgPreVoteResp from 9ef3b4f68930dc2 at term 1"} {"level":"info","ts":"2026-04-08T00:33:48.12987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became candidate at term 2"} {"level":"info","ts":"2026-04-08T00:33:48.129883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgVoteResp from 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-04-08T00:33:48.129904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became leader at term 2"} {"level":"info","ts":"2026-04-08T00:33:48.129918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9ef3b4f68930dc2 elected leader 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-04-08T00:33:48.130895Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:33:48.132849Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:33:48.132956Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:33:48.132983Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:33:48.133031Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9ef3b4f68930dc2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.141:2379]}","request-path":"/0/members/9ef3b4f68930dc2/attributes","cluster-id":"b19a5957e6e65747","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T00:33:48.133238Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:33:48.133617Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:33:48.133801Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T00:33:48.133823Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T00:33:48.136082Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T00:33:48.136719Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.141:2379"} {"level":"warn","ts":"2026-04-08T00:34:17.8331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.789388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-08T00:34:17.833064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.963039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T00:34:17.833191Z","caller":"traceutil/trace.go:171","msg":"trace[1768939789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:436; }","duration":"171.935401ms","start":"2026-04-08T00:34:17.661237Z","end":"2026-04-08T00:34:17.833172Z","steps":["trace[1768939789] 'range keys from in-memory index tree' (duration: 171.687235ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:17.833203Z","caller":"traceutil/trace.go:171","msg":"trace[2044717094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:436; }","duration":"171.141154ms","start":"2026-04-08T00:34:17.662043Z","end":"2026-04-08T00:34:17.833184Z","steps":["trace[2044717094] 'range keys from in-memory index tree' (duration: 170.852857ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:22.034559Z","caller":"traceutil/trace.go:171","msg":"trace[935306985] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"148.887512ms","start":"2026-04-08T00:34:21.885631Z","end":"2026-04-08T00:34:22.034518Z","steps":["trace[935306985] 'process raft request' (duration: 143.012629ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:22.034679Z","caller":"traceutil/trace.go:171","msg":"trace[1957659545] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"144.960384ms","start":"2026-04-08T00:34:21.889679Z","end":"2026-04-08T00:34:22.034639Z","steps":["trace[1957659545] 'process raft request' (duration: 144.75396ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:22.03481Z","caller":"traceutil/trace.go:171","msg":"trace[1554571826] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"143.187764ms","start":"2026-04-08T00:34:21.891605Z","end":"2026-04-08T00:34:22.034793Z","steps":["trace[1554571826] 'process raft request' (duration: 142.940188ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:22.034959Z","caller":"traceutil/trace.go:171","msg":"trace[668016049] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"128.862051ms","start":"2026-04-08T00:34:21.906091Z","end":"2026-04-08T00:34:22.034953Z","steps":["trace[668016049] 'process raft request' (duration: 128.541904ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:27.734504Z","caller":"traceutil/trace.go:171","msg":"trace[2123541207] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"130.932238ms","start":"2026-04-08T00:34:27.603545Z","end":"2026-04-08T00:34:27.734477Z","steps":["trace[2123541207] 'process raft request' (duration: 130.203001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:27.734774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.536199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T00:34:27.73487Z","caller":"traceutil/trace.go:171","msg":"trace[2044546407] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"102.679942ms","start":"2026-04-08T00:34:27.632164Z","end":"2026-04-08T00:34:27.734844Z","steps":["trace[2044546407] 'agreement among raft nodes before linearized reading' (duration: 102.404166ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:27.734367Z","caller":"traceutil/trace.go:171","msg":"trace[284499439] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"102.12413ms","start":"2026-04-08T00:34:27.632212Z","end":"2026-04-08T00:34:27.734336Z","steps":["trace[284499439] 'read index received' (duration: 101.186968ms)","trace[284499439] 'applied index is now lower than readState.Index' (duration: 935.702µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:27.928433Z","caller":"traceutil/trace.go:171","msg":"trace[296517446] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"188.889152ms","start":"2026-04-08T00:34:27.739516Z","end":"2026-04-08T00:34:27.928405Z","steps":["trace[296517446] 'process raft request' (duration: 188.736948ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:28.162018Z","caller":"traceutil/trace.go:171","msg":"trace[959800512] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:610; }","duration":"272.711979ms","start":"2026-04-08T00:34:27.88928Z","end":"2026-04-08T00:34:28.161992Z","steps":["trace[959800512] 'read index received' (duration: 39.069809ms)","trace[959800512] 'applied index is now lower than readState.Index' (duration: 233.64121ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:28.162092Z","caller":"traceutil/trace.go:171","msg":"trace[1352484016] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"360.791812ms","start":"2026-04-08T00:34:27.801263Z","end":"2026-04-08T00:34:28.162055Z","steps":["trace[1352484016] 'process raft request' (duration: 297.381965ms)","trace[1352484016] 'compare' (duration: 63.208862ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:34:28.162273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.970535ms","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-08T00:34:28.162315Z","caller":"traceutil/trace.go:171","msg":"trace[1714597077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"273.070937ms","start":"2026-04-08T00:34:27.889234Z","end":"2026-04-08T00:34:28.162305Z","steps":["trace[1714597077] 'agreement among raft nodes before linearized reading' (duration: 272.894093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:28.16238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.413228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T00:34:28.162476Z","caller":"traceutil/trace.go:171","msg":"trace[554565637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"266.544592ms","start":"2026-04-08T00:34:27.895915Z","end":"2026-04-08T00:34:28.162459Z","steps":["trace[554565637] 'agreement among raft nodes before linearized reading' (duration: 266.305335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:28.162606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.33336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:28.162664Z","caller":"traceutil/trace.go:171","msg":"trace[1022241774] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:589; }","duration":"124.399441ms","start":"2026-04-08T00:34:28.03823Z","end":"2026-04-08T00:34:28.16263Z","steps":["trace[1022241774] 'agreement among raft nodes before linearized reading' (duration: 124.34555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:28.16273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:27.801237Z","time spent":"360.932005ms","remote":"127.0.0.1:50990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T00:34:28.162806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.55922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:28.162845Z","caller":"traceutil/trace.go:171","msg":"trace[126418713] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:589; }","duration":"162.621871ms","start":"2026-04-08T00:34:28.000213Z","end":"2026-04-08T00:34:28.162835Z","steps":["trace[126418713] 'agreement among raft nodes before linearized reading' (duration: 162.5601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:28.162887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.686059ms","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-08T00:34:28.162923Z","caller":"traceutil/trace.go:171","msg":"trace[1917394687] 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:589; }","duration":"208.748351ms","start":"2026-04-08T00:34:27.954165Z","end":"2026-04-08T00:34:28.162913Z","steps":["trace[1917394687] 'agreement among raft nodes before linearized reading' (duration: 208.7001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:28.162844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.878771ms","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":"warn","ts":"2026-04-08T00:34:28.162985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.715433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:28.163013Z","caller":"traceutil/trace.go:171","msg":"trace[1643476541] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:589; }","duration":"162.757675ms","start":"2026-04-08T00:34:28.000248Z","end":"2026-04-08T00:34:28.163006Z","steps":["trace[1643476541] 'agreement among raft nodes before linearized reading' (duration: 162.715024ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:28.163041Z","caller":"traceutil/trace.go:171","msg":"trace[1946510751] 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:589; }","duration":"208.053995ms","start":"2026-04-08T00:34:27.954954Z","end":"2026-04-08T00:34:28.163008Z","steps":["trace[1946510751] 'agreement among raft nodes before linearized reading' (duration: 207.865071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:28.163083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.959856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:34:28.163104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.832355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tbt5j\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-08T00:34:28.163157Z","caller":"traceutil/trace.go:171","msg":"trace[1811974565] 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:589; }","duration":"209.070988ms","start":"2026-04-08T00:34:27.954066Z","end":"2026-04-08T00:34:28.163137Z","steps":["trace[1811974565] 'agreement among raft nodes before linearized reading' (duration: 208.922604ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:28.163176Z","caller":"traceutil/trace.go:171","msg":"trace[1175540728] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tbt5j; range_end:; response_count:1; response_revision:589; }","duration":"261.931958ms","start":"2026-04-08T00:34:27.901224Z","end":"2026-04-08T00:34:28.163156Z","steps":["trace[1175540728] 'agreement among raft nodes before linearized reading' (duration: 261.704593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:28.163195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.421284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:28.1633Z","caller":"traceutil/trace.go:171","msg":"trace[856118799] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:589; }","duration":"125.565717ms","start":"2026-04-08T00:34:28.037704Z","end":"2026-04-08T00:34:28.163269Z","steps":["trace[856118799] 'agreement among raft nodes before linearized reading' (duration: 125.351462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:28.163946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.949885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:28.164065Z","caller":"traceutil/trace.go:171","msg":"trace[41045819] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:589; }","duration":"126.373705ms","start":"2026-04-08T00:34:28.037667Z","end":"2026-04-08T00:34:28.164041Z","steps":["trace[41045819] 'agreement among raft nodes before linearized reading' (duration: 125.893314ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:28.302796Z","caller":"traceutil/trace.go:171","msg":"trace[775317821] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"136.568495ms","start":"2026-04-08T00:34:28.166205Z","end":"2026-04-08T00:34:28.302774Z","steps":["trace[775317821] 'process raft request' (duration: 127.863189ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:28.318258Z","caller":"traceutil/trace.go:171","msg":"trace[1680121435] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"151.48002ms","start":"2026-04-08T00:34:28.166737Z","end":"2026-04-08T00:34:28.318217Z","steps":["trace[1680121435] 'process raft request' (duration: 151.282706ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:28.318343Z","caller":"traceutil/trace.go:171","msg":"trace[1032573536] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:612; }","duration":"144.835471ms","start":"2026-04-08T00:34:28.17348Z","end":"2026-04-08T00:34:28.318315Z","steps":["trace[1032573536] 'read index received' (duration: 120.603326ms)","trace[1032573536] 'applied index is now lower than readState.Index' (duration: 24.230475ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:28.318356Z","caller":"traceutil/trace.go:171","msg":"trace[1186468124] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"141.74074ms","start":"2026-04-08T00:34:28.176587Z","end":"2026-04-08T00:34:28.318328Z","steps":["trace[1186468124] 'process raft request' (duration: 141.553186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:28.318463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.968584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:28.318513Z","caller":"traceutil/trace.go:171","msg":"trace[351523848] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:592; }","duration":"145.055656ms","start":"2026-04-08T00:34:28.17345Z","end":"2026-04-08T00:34:28.318505Z","steps":["trace[351523848] 'agreement among raft nodes before linearized reading' (duration: 144.971724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:28.318691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.529047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:28.318769Z","caller":"traceutil/trace.go:171","msg":"trace[254015308] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:592; }","duration":"110.62181ms","start":"2026-04-08T00:34:28.208128Z","end":"2026-04-08T00:34:28.31875Z","steps":["trace[254015308] 'agreement among raft nodes before linearized reading' (duration: 110.521128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:28.318768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.034389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:34:28.318693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.953307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:28.318926Z","caller":"traceutil/trace.go:171","msg":"trace[1024305425] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:592; }","duration":"111.194522ms","start":"2026-04-08T00:34:28.207685Z","end":"2026-04-08T00:34:28.318879Z","steps":["trace[1024305425] 'agreement among raft nodes before linearized reading' (duration: 110.907846ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:28.318936Z","caller":"traceutil/trace.go:171","msg":"trace[626923608] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:592; }","duration":"111.161762ms","start":"2026-04-08T00:34:28.207703Z","end":"2026-04-08T00:34:28.318865Z","steps":["trace[626923608] 'agreement among raft nodes before linearized reading' (duration: 111.034039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.042334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.070466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:34:29.042412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.701391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:29.042441Z","caller":"traceutil/trace.go:171","msg":"trace[934137956] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:593; }","duration":"523.203499ms","start":"2026-04-08T00:34:28.519222Z","end":"2026-04-08T00:34:29.042425Z","steps":["trace[934137956] 'range keys from in-memory index tree' (duration: 522.989364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.042479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:28.519215Z","time spent":"523.25499ms","remote":"127.0.0.1:58274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"info","ts":"2026-04-08T00:34:29.042477Z","caller":"traceutil/trace.go:171","msg":"trace[1141969065] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:593; }","duration":"535.821634ms","start":"2026-04-08T00:34:28.50664Z","end":"2026-04-08T00:34:29.042461Z","steps":["trace[1141969065] 'range keys from in-memory index tree' (duration: 535.581829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.042524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:28.506622Z","time spent":"535.882045ms","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-08T00:34:29.042542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.010995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:34:29.042482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.273325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:34:29.04263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.70104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:34:29.042713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.808461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:29.042742Z","caller":"traceutil/trace.go:171","msg":"trace[2143550847] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:593; }","duration":"522.855741ms","start":"2026-04-08T00:34:28.519878Z","end":"2026-04-08T00:34:29.042734Z","steps":["trace[2143550847] 'range keys from in-memory index tree' (duration: 522.78564ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:29.042732Z","caller":"traceutil/trace.go:171","msg":"trace[1491879158] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:593; }","duration":"634.863794ms","start":"2026-04-08T00:34:28.407846Z","end":"2026-04-08T00:34:29.04271Z","steps":["trace[1491879158] 'range keys from in-memory index tree' (duration: 634.603358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.042772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:28.519873Z","time spent":"522.891622ms","remote":"127.0.0.1:58274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"info","ts":"2026-04-08T00:34:29.042792Z","caller":"traceutil/trace.go:171","msg":"trace[1849453996] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:593; }","duration":"393.609172ms","start":"2026-04-08T00:34:28.649163Z","end":"2026-04-08T00:34:29.042772Z","steps":["trace[1849453996] 'range keys from in-memory index tree' (duration: 393.197003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.042961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:28.649156Z","time spent":"393.792316ms","remote":"127.0.0.1:58276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-08T00:34:29.042853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.061458ms","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":"warn","ts":"2026-04-08T00:34:29.04289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.659753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:29.043041Z","caller":"traceutil/trace.go:171","msg":"trace[1561826170] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:593; }","duration":"393.822577ms","start":"2026-04-08T00:34:28.649206Z","end":"2026-04-08T00:34:29.043029Z","steps":["trace[1561826170] 'count revisions from in-memory index tree' (duration: 393.636692ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:29.04304Z","caller":"traceutil/trace.go:171","msg":"trace[488295919] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:593; }","duration":"635.257812ms","start":"2026-04-08T00:34:28.407767Z","end":"2026-04-08T00:34:29.043025Z","steps":["trace[488295919] 'count revisions from in-memory index tree' (duration: 635.016827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.04271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.629699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:29.043135Z","caller":"traceutil/trace.go:171","msg":"trace[600768109] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:593; }","duration":"635.025977ms","start":"2026-04-08T00:34:28.408071Z","end":"2026-04-08T00:34:29.043097Z","steps":["trace[600768109] 'range keys from in-memory index tree' (duration: 634.579437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.043075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:28.649204Z","time spent":"393.860808ms","remote":"127.0.0.1:58276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-08T00:34:29.043175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:28.408038Z","time spent":"635.123879ms","remote":"127.0.0.1:58258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 "} {"level":"warn","ts":"2026-04-08T00:34:29.042672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.942247ms","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":"warn","ts":"2026-04-08T00:34:29.043327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:28.407759Z","time spent":"635.316244ms","remote":"127.0.0.1:58258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-04-08T00:34:29.043367Z","caller":"traceutil/trace.go:171","msg":"trace[527719359] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:593; }","duration":"536.644192ms","start":"2026-04-08T00:34:28.506714Z","end":"2026-04-08T00:34:29.043359Z","steps":["trace[527719359] 'count revisions from in-memory index tree' (duration: 535.865424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.043424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:28.506695Z","time spent":"536.718033ms","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-08T00:34:29.042801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:28.40783Z","time spent":"634.954325ms","remote":"127.0.0.1:58258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 "} {"level":"warn","ts":"2026-04-08T00:34:29.042822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.536237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:29.043623Z","caller":"traceutil/trace.go:171","msg":"trace[2131515207] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:593; }","duration":"524.345885ms","start":"2026-04-08T00:34:28.519264Z","end":"2026-04-08T00:34:29.04361Z","steps":["trace[2131515207] 'count revisions from in-memory index tree' (duration: 523.510166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.043693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:28.51926Z","time spent":"524.417596ms","remote":"127.0.0.1:58274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-04-08T00:34:29.042627Z","caller":"traceutil/trace.go:171","msg":"trace[1130540106] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:593; }","duration":"535.154169ms","start":"2026-04-08T00:34:28.507455Z","end":"2026-04-08T00:34:29.042609Z","steps":["trace[1130540106] 'range keys from in-memory index tree' (duration: 534.960844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.043734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:28.507449Z","time spent":"536.277123ms","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"info","ts":"2026-04-08T00:34:29.046613Z","caller":"traceutil/trace.go:171","msg":"trace[1436672611] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"240.28211ms","start":"2026-04-08T00:34:28.806311Z","end":"2026-04-08T00:34:29.046593Z","steps":["trace[1436672611] 'read index received' (duration: 239.416781ms)","trace[1436672611] 'applied index is now lower than readState.Index' (duration: 864.329µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:29.046744Z","caller":"traceutil/trace.go:171","msg":"trace[2020870155] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"337.707522ms","start":"2026-04-08T00:34:28.70899Z","end":"2026-04-08T00:34:29.046698Z","steps":["trace[2020870155] 'process raft request' (duration: 337.023677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.046771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.443393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:29.046833Z","caller":"traceutil/trace.go:171","msg":"trace[1495670438] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:594; }","duration":"240.532316ms","start":"2026-04-08T00:34:28.806277Z","end":"2026-04-08T00:34:29.04681Z","steps":["trace[1495670438] 'agreement among raft nodes before linearized reading' (duration: 240.440724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.046876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.883139ms","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-08T00:34:29.046922Z","caller":"traceutil/trace.go:171","msg":"trace[1041455105] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:594; }","duration":"127.95236ms","start":"2026-04-08T00:34:28.918959Z","end":"2026-04-08T00:34:29.046911Z","steps":["trace[1041455105] 'agreement among raft nodes before linearized reading' (duration: 127.856948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.047058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:28.708971Z","time spent":"337.826675ms","remote":"127.0.0.1:50544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:34:29.047067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.778012ms","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-08T00:34:29.047113Z","caller":"traceutil/trace.go:171","msg":"trace[1692178100] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:594; }","duration":"185.851254ms","start":"2026-04-08T00:34:28.861249Z","end":"2026-04-08T00:34:29.0471Z","steps":["trace[1692178100] 'agreement among raft nodes before linearized reading' (duration: 185.780443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.047154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.052572ms","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-08T00:34:29.047184Z","caller":"traceutil/trace.go:171","msg":"trace[987872287] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:594; }","duration":"128.116024ms","start":"2026-04-08T00:34:28.919062Z","end":"2026-04-08T00:34:29.047178Z","steps":["trace[987872287] 'agreement among raft nodes before linearized reading' (duration: 128.070633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.04728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.403234ms","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-08T00:34:29.04729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.090123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:29.047318Z","caller":"traceutil/trace.go:171","msg":"trace[160744732] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:594; }","duration":"157.461295ms","start":"2026-04-08T00:34:28.889847Z","end":"2026-04-08T00:34:29.047309Z","steps":["trace[160744732] 'agreement among raft nodes before linearized reading' (duration: 157.393253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.047158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.787174ms","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-08T00:34:29.047352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.029936ms","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":"info","ts":"2026-04-08T00:34:29.047381Z","caller":"traceutil/trace.go:171","msg":"trace[2050738229] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:594; }","duration":"127.079881ms","start":"2026-04-08T00:34:28.92029Z","end":"2026-04-08T00:34:29.04737Z","steps":["trace[2050738229] 'agreement among raft nodes before linearized reading' (duration: 126.835916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.047395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.564887ms","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":"warn","ts":"2026-04-08T00:34:29.047448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.205201ms","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":"warn","ts":"2026-04-08T00:34:29.047417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.115598ms","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":"warn","ts":"2026-04-08T00:34:29.04747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.890235ms","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-08T00:34:29.047485Z","caller":"traceutil/trace.go:171","msg":"trace[505136285] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:594; }","duration":"186.263383ms","start":"2026-04-08T00:34:28.861211Z","end":"2026-04-08T00:34:29.047474Z","steps":["trace[505136285] 'agreement among raft nodes before linearized reading' (duration: 186.208622ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:29.047501Z","caller":"traceutil/trace.go:171","msg":"trace[849601737] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:594; }","duration":"185.937326ms","start":"2026-04-08T00:34:28.861555Z","end":"2026-04-08T00:34:29.047492Z","steps":["trace[849601737] 'agreement among raft nodes before linearized reading' (duration: 185.889985ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:29.047339Z","caller":"traceutil/trace.go:171","msg":"trace[2018274099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"176.150275ms","start":"2026-04-08T00:34:28.871175Z","end":"2026-04-08T00:34:29.047325Z","steps":["trace[2018274099] 'agreement among raft nodes before linearized reading' (duration: 176.078113ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:29.047547Z","caller":"traceutil/trace.go:171","msg":"trace[275493027] 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:594; }","duration":"217.20608ms","start":"2026-04-08T00:34:28.830279Z","end":"2026-04-08T00:34:29.047485Z","steps":["trace[275493027] 'agreement among raft nodes before linearized reading' (duration: 217.063116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.047626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.142897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:29.047402Z","caller":"traceutil/trace.go:171","msg":"trace[1846565908] 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:594; }","duration":"217.109068ms","start":"2026-04-08T00:34:28.830282Z","end":"2026-04-08T00:34:29.047391Z","steps":["trace[1846565908] 'agreement among raft nodes before linearized reading' (duration: 217.034286ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:29.047661Z","caller":"traceutil/trace.go:171","msg":"trace[363873516] 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:594; }","duration":"216.198418ms","start":"2026-04-08T00:34:28.831455Z","end":"2026-04-08T00:34:29.047653Z","steps":["trace[363873516] 'agreement among raft nodes before linearized reading' (duration: 216.147326ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:29.047446Z","caller":"traceutil/trace.go:171","msg":"trace[748391138] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:594; }","duration":"157.630058ms","start":"2026-04-08T00:34:28.889804Z","end":"2026-04-08T00:34:29.047434Z","steps":["trace[748391138] 'agreement among raft nodes before linearized reading' (duration: 157.558927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.048096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.149029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:29.048231Z","caller":"traceutil/trace.go:171","msg":"trace[2012844301] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:594; }","duration":"241.247551ms","start":"2026-04-08T00:34:28.806902Z","end":"2026-04-08T00:34:29.048149Z","steps":["trace[2012844301] 'agreement among raft nodes before linearized reading' (duration: 241.139999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.04848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.504435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:29.048531Z","caller":"traceutil/trace.go:171","msg":"trace[1270851136] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:594; }","duration":"242.182393ms","start":"2026-04-08T00:34:28.806333Z","end":"2026-04-08T00:34:29.048515Z","steps":["trace[1270851136] 'agreement among raft nodes before linearized reading' (duration: 240.457754ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:42.699457Z","caller":"traceutil/trace.go:171","msg":"trace[1333098483] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"281.920344ms","start":"2026-04-08T00:34:42.417512Z","end":"2026-04-08T00:34:42.699432Z","steps":["trace[1333098483] 'read index received' (duration: 281.667798ms)","trace[1333098483] 'applied index is now lower than readState.Index' (duration: 251.596µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:34:42.699757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.519607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-08T00:34:42.699543Z","caller":"traceutil/trace.go:171","msg":"trace[1352062288] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"313.825997ms","start":"2026-04-08T00:34:42.385681Z","end":"2026-04-08T00:34:42.699507Z","steps":["trace[1352062288] 'process raft request' (duration: 313.566181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:42.699776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.722633ms","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-08T00:34:42.699805Z","caller":"traceutil/trace.go:171","msg":"trace[1346727472] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:744; }","duration":"263.629829ms","start":"2026-04-08T00:34:42.436164Z","end":"2026-04-08T00:34:42.699794Z","steps":["trace[1346727472] 'agreement among raft nodes before linearized reading' (duration: 263.444125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:42.699775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.233172ms","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-08T00:34:42.69986Z","caller":"traceutil/trace.go:171","msg":"trace[791715894] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:744; }","duration":"224.833575ms","start":"2026-04-08T00:34:42.475004Z","end":"2026-04-08T00:34:42.699837Z","steps":["trace[791715894] 'agreement among raft nodes before linearized reading' (duration: 224.651151ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:42.699876Z","caller":"traceutil/trace.go:171","msg":"trace[875574387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:744; }","duration":"282.385456ms","start":"2026-04-08T00:34:42.417472Z","end":"2026-04-08T00:34:42.699857Z","steps":["trace[875574387] 'agreement among raft nodes before linearized reading' (duration: 282.077568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:42.699919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:42.385648Z","time spent":"314.177096ms","remote":"127.0.0.1:50736","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-08T00:34:46.296825Z","caller":"traceutil/trace.go:171","msg":"trace[1069046303] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"239.45103ms","start":"2026-04-08T00:34:46.057344Z","end":"2026-04-08T00:34:46.296795Z","steps":["trace[1069046303] 'read index received' (duration: 239.262975ms)","trace[1069046303] 'applied index is now lower than readState.Index' (duration: 186.815µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:46.296914Z","caller":"traceutil/trace.go:171","msg":"trace[1518873401] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"283.632736ms","start":"2026-04-08T00:34:46.013252Z","end":"2026-04-08T00:34:46.296885Z","steps":["trace[1518873401] 'process raft request' (duration: 283.346439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:46.297079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.713476ms","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-08T00:34:46.297136Z","caller":"traceutil/trace.go:171","msg":"trace[1214382935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:763; }","duration":"239.826829ms","start":"2026-04-08T00:34:46.057294Z","end":"2026-04-08T00:34:46.297121Z","steps":["trace[1214382935] 'agreement among raft nodes before linearized reading' (duration: 239.622264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:46.467954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.014234ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-08T00:34:46.468355Z","caller":"traceutil/trace.go:171","msg":"trace[601664787] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"166.528437ms","start":"2026-04-08T00:34:46.301807Z","end":"2026-04-08T00:34:46.468335Z","steps":["trace[601664787] 'process raft request' (duration: 166.328762ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:50.88036Z","caller":"traceutil/trace.go:171","msg":"trace[823306532] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"146.140961ms","start":"2026-04-08T00:34:50.7342Z","end":"2026-04-08T00:34:50.880341Z","steps":["trace[823306532] 'read index received' (duration: 144.88908ms)","trace[823306532] 'applied index is now lower than readState.Index' (duration: 1.250901ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:50.880495Z","caller":"traceutil/trace.go:171","msg":"trace[2088880668] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"199.449255ms","start":"2026-04-08T00:34:50.681036Z","end":"2026-04-08T00:34:50.880486Z","steps":["trace[2088880668] 'process raft request' (duration: 198.437461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:50.880738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.053044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-08T00:34:50.880807Z","caller":"traceutil/trace.go:171","msg":"trace[960474562] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:809; }","duration":"141.156707ms","start":"2026-04-08T00:34:50.739634Z","end":"2026-04-08T00:34:50.880791Z","steps":["trace[960474562] 'agreement among raft nodes before linearized reading' (duration: 140.956992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:50.880837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.394848ms","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-08T00:34:50.880871Z","caller":"traceutil/trace.go:171","msg":"trace[844266012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:809; }","duration":"140.47022ms","start":"2026-04-08T00:34:50.740393Z","end":"2026-04-08T00:34:50.880863Z","steps":["trace[844266012] 'agreement among raft nodes before linearized reading' (duration: 140.347497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:50.880852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.639203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T00:34:50.880962Z","caller":"traceutil/trace.go:171","msg":"trace[2027867443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:809; }","duration":"146.791766ms","start":"2026-04-08T00:34:50.734143Z","end":"2026-04-08T00:34:50.880934Z","steps":["trace[2027867443] 'agreement among raft nodes before linearized reading' (duration: 146.503459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:50.880968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.158232ms","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-08T00:34:50.881086Z","caller":"traceutil/trace.go:171","msg":"trace[1304635759] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:809; }","duration":"136.306595ms","start":"2026-04-08T00:34:50.744751Z","end":"2026-04-08T00:34:50.881058Z","steps":["trace[1304635759] 'agreement among raft nodes before linearized reading' (duration: 135.932296ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:51.417226Z","caller":"traceutil/trace.go:171","msg":"trace[2042104425] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"142.425489ms","start":"2026-04-08T00:34:51.274778Z","end":"2026-04-08T00:34:51.417204Z","steps":["trace[2042104425] 'process raft request' (duration: 142.286116ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:52.441338Z","caller":"traceutil/trace.go:171","msg":"trace[1769330735] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"111.272424ms","start":"2026-04-08T00:34:52.330042Z","end":"2026-04-08T00:34:52.441315Z","steps":["trace[1769330735] 'process raft request' (duration: 84.411887ms)","trace[1769330735] 'compare' (duration: 26.766425ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:52.469072Z","caller":"traceutil/trace.go:171","msg":"trace[1440838255] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:851; }","duration":"127.524368ms","start":"2026-04-08T00:34:52.341512Z","end":"2026-04-08T00:34:52.469036Z","steps":["trace[1440838255] 'read index received' (duration: 72.954273ms)","trace[1440838255] 'applied index is now lower than readState.Index' (duration: 54.569095ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:34:52.469436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.892857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:10000 ","response":"range_response_count:3 size:1862"} {"level":"info","ts":"2026-04-08T00:34:52.46949Z","caller":"traceutil/trace.go:171","msg":"trace[571391666] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/; range_end:/registry/serviceaccounts/envoy-gateway-system0; response_count:3; response_revision:823; }","duration":"128.0053ms","start":"2026-04-08T00:34:52.341471Z","end":"2026-04-08T00:34:52.469476Z","steps":["trace[571391666] 'agreement among raft nodes before linearized reading' (duration: 127.670982ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:52.469864Z","caller":"traceutil/trace.go:171","msg":"trace[1042897481] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"139.648859ms","start":"2026-04-08T00:34:52.330204Z","end":"2026-04-08T00:34:52.469853Z","steps":["trace[1042897481] 'process raft request' (duration: 138.661745ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:54.102052Z","caller":"traceutil/trace.go:171","msg":"trace[297924634] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"150.826627ms","start":"2026-04-08T00:34:53.9512Z","end":"2026-04-08T00:34:54.102027Z","steps":["trace[297924634] 'process raft request' (duration: 146.961681ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:54.167035Z","caller":"traceutil/trace.go:171","msg":"trace[1618592970] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"215.696559ms","start":"2026-04-08T00:34:53.951311Z","end":"2026-04-08T00:34:54.167007Z","steps":["trace[1618592970] 'process raft request' (duration: 215.464853ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:54.167502Z","caller":"traceutil/trace.go:171","msg":"trace[682851778] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"214.749085ms","start":"2026-04-08T00:34:53.952735Z","end":"2026-04-08T00:34:54.167484Z","steps":["trace[682851778] 'process raft request' (duration: 214.195021ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:54.167621Z","caller":"traceutil/trace.go:171","msg":"trace[397273376] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"113.856528ms","start":"2026-04-08T00:34:54.05366Z","end":"2026-04-08T00:34:54.167516Z","steps":["trace[397273376] 'process raft request' (duration: 113.560161ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:54.367415Z","caller":"traceutil/trace.go:171","msg":"trace[200595910] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"191.362234ms","start":"2026-04-08T00:34:54.176031Z","end":"2026-04-08T00:34:54.367393Z","steps":["trace[200595910] 'process raft request' (duration: 191.21319ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:54.583601Z","caller":"traceutil/trace.go:171","msg":"trace[581392834] linearizableReadLoop","detail":"{readStateIndex:873; appliedIndex:872; }","duration":"202.217534ms","start":"2026-04-08T00:34:54.381364Z","end":"2026-04-08T00:34:54.583581Z","steps":["trace[581392834] 'read index received' (duration: 202.128572ms)","trace[581392834] 'applied index is now lower than readState.Index' (duration: 88.262µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:54.583649Z","caller":"traceutil/trace.go:171","msg":"trace[204863432] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"205.512105ms","start":"2026-04-08T00:34:54.378118Z","end":"2026-04-08T00:34:54.583631Z","steps":["trace[204863432] 'process raft request' (duration: 205.352911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:54.583772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.385408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-04-08T00:34:54.583869Z","caller":"traceutil/trace.go:171","msg":"trace[1179946329] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:842; }","duration":"202.4785ms","start":"2026-04-08T00:34:54.381348Z","end":"2026-04-08T00:34:54.583826Z","steps":["trace[1179946329] 'agreement among raft nodes before linearized reading' (duration: 202.344796ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:54.586054Z","caller":"traceutil/trace.go:171","msg":"trace[284126242] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"112.737491ms","start":"2026-04-08T00:34:54.473298Z","end":"2026-04-08T00:34:54.586036Z","steps":["trace[284126242] 'process raft request' (duration: 112.470554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:54.58646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.584313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-04-08T00:34:54.58649Z","caller":"traceutil/trace.go:171","msg":"trace[1484269133] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:843; }","duration":"204.659445ms","start":"2026-04-08T00:34:54.381823Z","end":"2026-04-08T00:34:54.586483Z","steps":["trace[1484269133] 'agreement among raft nodes before linearized reading' (duration: 204.592493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:54.985431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.777021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:54.985517Z","caller":"traceutil/trace.go:171","msg":"trace[1382835494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:845; }","duration":"110.903745ms","start":"2026-04-08T00:34:54.874596Z","end":"2026-04-08T00:34:54.9855Z","steps":["trace[1382835494] 'range keys from in-memory index tree' (duration: 110.684109ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:55.159292Z","caller":"traceutil/trace.go:171","msg":"trace[1499299033] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"169.295786ms","start":"2026-04-08T00:34:54.989975Z","end":"2026-04-08T00:34:55.159271Z","steps":["trace[1499299033] 'process raft request' (duration: 169.158232ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:55.429386Z","caller":"traceutil/trace.go:171","msg":"trace[1208467849] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"439.378956ms","start":"2026-04-08T00:34:54.989985Z","end":"2026-04-08T00:34:55.429364Z","steps":["trace[1208467849] 'process raft request' (duration: 439.184481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:55.429534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:54.989961Z","time spent":"439.501329ms","remote":"127.0.0.1:50736","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-08T00:34:55.42978Z","caller":"traceutil/trace.go:171","msg":"trace[42343546] linearizableReadLoop","detail":"{readStateIndex:881; appliedIndex:877; }","duration":"226.097298ms","start":"2026-04-08T00:34:55.203663Z","end":"2026-04-08T00:34:55.429761Z","steps":["trace[42343546] 'read index received' (duration: 225.402521ms)","trace[42343546] 'applied index is now lower than readState.Index' (duration: 694.046µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:55.429859Z","caller":"traceutil/trace.go:171","msg":"trace[648722663] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"438.187156ms","start":"2026-04-08T00:34:54.991626Z","end":"2026-04-08T00:34:55.429813Z","steps":["trace[648722663] 'process raft request' (duration: 438.001381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:55.429881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.2035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:34:55.429942Z","caller":"traceutil/trace.go:171","msg":"trace[681216580] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:850; }","duration":"226.310933ms","start":"2026-04-08T00:34:55.203621Z","end":"2026-04-08T00:34:55.429932Z","steps":["trace[681216580] 'agreement among raft nodes before linearized reading' (duration: 226.2111ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:55.429913Z","caller":"traceutil/trace.go:171","msg":"trace[1136126934] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"439.835728ms","start":"2026-04-08T00:34:54.990054Z","end":"2026-04-08T00:34:55.42989Z","steps":["trace[1136126934] 'process raft request' (duration: 439.272894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:55.430012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:54.991613Z","time spent":"438.32272ms","remote":"127.0.0.1:50736","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-08T00:34:55.42998Z","caller":"traceutil/trace.go:171","msg":"trace[351674204] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"328.229504ms","start":"2026-04-08T00:34:55.101717Z","end":"2026-04-08T00:34:55.429946Z","steps":["trace[351674204] 'process raft request' (duration: 328.004329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:55.430034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:54.990034Z","time spent":"439.94472ms","remote":"127.0.0.1:50736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:34:55.430138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:55.101697Z","time spent":"328.373578ms","remote":"127.0.0.1:58398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2259,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:35:14.705907Z","caller":"traceutil/trace.go:171","msg":"trace[762976310] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"320.77801ms","start":"2026-04-08T00:35:14.385095Z","end":"2026-04-08T00:35:14.705873Z","steps":["trace[762976310] 'process raft request' (duration: 320.526694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:14.706152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:35:14.385058Z","time spent":"320.937874ms","remote":"127.0.0.1:50736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:35:28.365537Z","caller":"traceutil/trace.go:171","msg":"trace[1741252536] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"152.766696ms","start":"2026-04-08T00:35:28.212746Z","end":"2026-04-08T00:35:28.365513Z","steps":["trace[1741252536] 'process raft request' (duration: 152.621632ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:33.755609Z","caller":"traceutil/trace.go:171","msg":"trace[883574662] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"101.03374ms","start":"2026-04-08T00:35:33.654539Z","end":"2026-04-08T00:35:33.755572Z","steps":["trace[883574662] 'process raft request' (duration: 100.795274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:36.619647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.607438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:35:36.619757Z","caller":"traceutil/trace.go:171","msg":"trace[306103660] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1417; }","duration":"132.797943ms","start":"2026-04-08T00:35:36.48694Z","end":"2026-04-08T00:35:36.619738Z","steps":["trace[306103660] 'count revisions from in-memory index tree' (duration: 132.500265ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:37.197642Z","caller":"traceutil/trace.go:171","msg":"trace[447442822] linearizableReadLoop","detail":"{readStateIndex:1463; appliedIndex:1462; }","duration":"384.718971ms","start":"2026-04-08T00:35:36.812891Z","end":"2026-04-08T00:35:37.19761Z","steps":["trace[447442822] 'read index received' (duration: 384.382334ms)","trace[447442822] 'applied index is now lower than readState.Index' (duration: 335.337µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:35:37.197789Z","caller":"traceutil/trace.go:171","msg":"trace[1168476470] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"399.401231ms","start":"2026-04-08T00:35:36.798328Z","end":"2026-04-08T00:35:37.197729Z","steps":["trace[1168476470] 'process raft request' (duration: 399.036033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:37.198021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:35:36.798297Z","time spent":"399.601145ms","remote":"127.0.0.1:50736","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-08T00:35:37.198032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.105839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-08T00:35:37.19809Z","caller":"traceutil/trace.go:171","msg":"trace[246295524] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1418; }","duration":"385.249812ms","start":"2026-04-08T00:35:36.812826Z","end":"2026-04-08T00:35:37.198076Z","steps":["trace[246295524] 'agreement among raft nodes before linearized reading' (duration: 384.947556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:37.198128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.502749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T00:35:37.198211Z","caller":"traceutil/trace.go:171","msg":"trace[440398235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1418; }","duration":"378.604102ms","start":"2026-04-08T00:35:36.819585Z","end":"2026-04-08T00:35:37.198189Z","steps":["trace[440398235] 'agreement among raft nodes before linearized reading' (duration: 378.248814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:37.198256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:35:36.819575Z","time spent":"378.669943ms","remote":"127.0.0.1:50736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T00:35:37.198146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:35:36.812812Z","time spent":"385.322214ms","remote":"127.0.0.1:50526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":165,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-08T00:35:39.810023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.933996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:35:39.810126Z","caller":"traceutil/trace.go:171","msg":"trace[1168579329] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"159.378412ms","start":"2026-04-08T00:35:39.650726Z","end":"2026-04-08T00:35:39.810104Z","steps":["trace[1168579329] 'process raft request' (duration: 57.290972ms)","trace[1168579329] 'compare' (duration: 101.778613ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:35:39.822591Z","caller":"traceutil/trace.go:171","msg":"trace[1436191948] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"154.88507ms","start":"2026-04-08T00:35:39.667687Z","end":"2026-04-08T00:35:39.822572Z","steps":["trace[1436191948] 'process raft request' (duration: 154.693096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:00.338041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.251387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-08T00:36:00.33817Z","caller":"traceutil/trace.go:171","msg":"trace[595330477] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1824; }","duration":"186.43623ms","start":"2026-04-08T00:36:00.151712Z","end":"2026-04-08T00:36:00.338149Z","steps":["trace[595330477] 'range keys from in-memory index tree' (duration: 186.058183ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:20.698751Z","caller":"traceutil/trace.go:171","msg":"trace[1892380649] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"149.718944ms","start":"2026-04-08T00:36:20.549002Z","end":"2026-04-08T00:36:20.698721Z","steps":["trace[1892380649] 'process raft request' (duration: 81.127118ms)","trace[1892380649] 'compare' (duration: 68.467193ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:36:44.209226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.836125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12379"} {"level":"info","ts":"2026-04-08T00:36:44.209319Z","caller":"traceutil/trace.go:171","msg":"trace[998440307] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2111; }","duration":"101.022899ms","start":"2026-04-08T00:36:44.108279Z","end":"2026-04-08T00:36:44.209302Z","steps":["trace[998440307] 'range keys from in-memory index tree' (duration: 100.56938ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:44.395539Z","caller":"traceutil/trace.go:171","msg":"trace[600274357] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"181.486709ms","start":"2026-04-08T00:36:44.214025Z","end":"2026-04-08T00:36:44.395512Z","steps":["trace[600274357] 'process raft request' (duration: 181.290645ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:44.490146Z","caller":"traceutil/trace.go:171","msg":"trace[1895851793] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"276.051698ms","start":"2026-04-08T00:36:44.214072Z","end":"2026-04-08T00:36:44.490123Z","steps":["trace[1895851793] 'process raft request' (duration: 207.474105ms)","trace[1895851793] 'compare' (duration: 68.43585ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:44.490296Z","caller":"traceutil/trace.go:171","msg":"trace[616399587] linearizableReadLoop","detail":"{readStateIndex:2194; appliedIndex:2191; }","duration":"266.233715ms","start":"2026-04-08T00:36:44.224017Z","end":"2026-04-08T00:36:44.490251Z","steps":["trace[616399587] 'read index received' (duration: 171.275839ms)","trace[616399587] 'applied index is now lower than readState.Index' (duration: 94.956526ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:36:44.490501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.46227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:44.490546Z","caller":"traceutil/trace.go:171","msg":"trace[1728586987] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2114; }","duration":"266.544771ms","start":"2026-04-08T00:36:44.223988Z","end":"2026-04-08T00:36:44.490533Z","steps":["trace[1728586987] 'agreement among raft nodes before linearized reading' (duration: 266.395258ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:44.490569Z","caller":"traceutil/trace.go:171","msg":"trace[1553940238] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"276.417326ms","start":"2026-04-08T00:36:44.214134Z","end":"2026-04-08T00:36:44.490552Z","steps":["trace[1553940238] 'process raft request' (duration: 275.950046ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:45.771499Z","caller":"traceutil/trace.go:171","msg":"trace[1659363349] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"167.922395ms","start":"2026-04-08T00:36:45.603551Z","end":"2026-04-08T00:36:45.771473Z","steps":["trace[1659363349] 'process raft request' (duration: 167.726391ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:45.774679Z","caller":"traceutil/trace.go:171","msg":"trace[1334731986] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"141.963381ms","start":"2026-04-08T00:36:45.63268Z","end":"2026-04-08T00:36:45.774643Z","steps":["trace[1334731986] 'process raft request' (duration: 141.541681ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:11.989408Z","caller":"traceutil/trace.go:171","msg":"trace[302253095] transaction","detail":"{read_only:false; response_revision:2267; number_of_response:1; }","duration":"132.657527ms","start":"2026-04-08T00:37:11.856729Z","end":"2026-04-08T00:37:11.989387Z","steps":["trace[302253095] 'process raft request' (duration: 132.510304ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:12.0987Z","caller":"traceutil/trace.go:171","msg":"trace[1650175994] transaction","detail":"{read_only:false; response_revision:2268; number_of_response:1; }","duration":"241.20397ms","start":"2026-04-08T00:37:11.857476Z","end":"2026-04-08T00:37:12.09868Z","steps":["trace[1650175994] 'process raft request' (duration: 212.973445ms)","trace[1650175994] 'compare' (duration: 27.989009ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:37:12.099016Z","caller":"traceutil/trace.go:171","msg":"trace[195899494] linearizableReadLoop","detail":"{readStateIndex:2356; appliedIndex:2353; }","duration":"205.436852ms","start":"2026-04-08T00:37:11.893553Z","end":"2026-04-08T00:37:12.09899Z","steps":["trace[195899494] 'read index received' (duration: 95.731194ms)","trace[195899494] 'applied index is now lower than readState.Index' (duration: 109.703738ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:37:12.099146Z","caller":"traceutil/trace.go:171","msg":"trace[643915064] transaction","detail":"{read_only:false; response_revision:2269; number_of_response:1; }","duration":"240.431333ms","start":"2026-04-08T00:37:11.858704Z","end":"2026-04-08T00:37:12.099135Z","steps":["trace[643915064] 'process raft request' (duration: 239.942232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:12.099435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.869921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-08T00:37:12.099436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.136024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-08T00:37:12.099535Z","caller":"traceutil/trace.go:171","msg":"trace[2053851285] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2269; }","duration":"193.296487ms","start":"2026-04-08T00:37:11.906219Z","end":"2026-04-08T00:37:12.099515Z","steps":["trace[2053851285] 'agreement among raft nodes before linearized reading' (duration: 192.979471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:12.099601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.351292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5460"} {"level":"warn","ts":"2026-04-08T00:37:12.099406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.390163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:37:12.099696Z","caller":"traceutil/trace.go:171","msg":"trace[1823182277] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2269; }","duration":"205.967323ms","start":"2026-04-08T00:37:11.893503Z","end":"2026-04-08T00:37:12.099471Z","steps":["trace[1823182277] 'agreement among raft nodes before linearized reading' (duration: 205.772389ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:12.099692Z","caller":"traceutil/trace.go:171","msg":"trace[2001355688] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2269; }","duration":"144.500466ms","start":"2026-04-08T00:37:11.95517Z","end":"2026-04-08T00:37:12.099671Z","steps":["trace[2001355688] 'agreement among raft nodes before linearized reading' (duration: 144.266301ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:12.099738Z","caller":"traceutil/trace.go:171","msg":"trace[1181865360] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:2269; }","duration":"110.776391ms","start":"2026-04-08T00:37:11.988929Z","end":"2026-04-08T00:37:12.099705Z","steps":["trace[1181865360] 'agreement among raft nodes before linearized reading' (duration: 110.342822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:38.128695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.878385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:37:38.128797Z","caller":"traceutil/trace.go:171","msg":"trace[1484930825] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2572; }","duration":"237.031688ms","start":"2026-04-08T00:37:37.891746Z","end":"2026-04-08T00:37:38.128778Z","steps":["trace[1484930825] 'count revisions from in-memory index tree' (duration: 236.795403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:38.128992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.839918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:37:38.12908Z","caller":"traceutil/trace.go:171","msg":"trace[858733716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2572; }","duration":"255.975221ms","start":"2026-04-08T00:37:37.873088Z","end":"2026-04-08T00:37:38.129063Z","steps":["trace[858733716] 'range keys from in-memory index tree' (duration: 255.747376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:38.129356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.263635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-08T00:37:38.129443Z","caller":"traceutil/trace.go:171","msg":"trace[957499273] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2572; }","duration":"345.399538ms","start":"2026-04-08T00:37:37.784026Z","end":"2026-04-08T00:37:38.129425Z","steps":["trace[957499273] 'range keys from in-memory index tree' (duration: 344.858636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:38.12949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:37.783999Z","time spent":"345.48305ms","remote":"127.0.0.1:50648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-04-08T00:38:12.342769Z","caller":"traceutil/trace.go:171","msg":"trace[2002997380] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"103.61288ms","start":"2026-04-08T00:38:12.239142Z","end":"2026-04-08T00:38:12.342755Z","steps":["trace[2002997380] 'process raft request' (duration: 103.518327ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:40:33.016661Z","caller":"traceutil/trace.go:171","msg":"trace[1539428721] transaction","detail":"{read_only:false; response_revision:3722; number_of_response:1; }","duration":"166.831664ms","start":"2026-04-08T00:40:32.849799Z","end":"2026-04-08T00:40:33.016631Z","steps":["trace[1539428721] 'process raft request' (duration: 166.618429ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:40:33.016877Z","caller":"traceutil/trace.go:171","msg":"trace[2026612293] linearizableReadLoop","detail":"{readStateIndex:3864; appliedIndex:3861; }","duration":"146.499702ms","start":"2026-04-08T00:40:32.870342Z","end":"2026-04-08T00:40:33.016842Z","steps":["trace[2026612293] 'read index received' (duration: 10.905088ms)","trace[2026612293] 'applied index is now lower than readState.Index' (duration: 135.591344ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:40:33.016912Z","caller":"traceutil/trace.go:171","msg":"trace[1840411973] transaction","detail":"{read_only:false; response_revision:3723; number_of_response:1; }","duration":"166.66466ms","start":"2026-04-08T00:40:32.850221Z","end":"2026-04-08T00:40:33.016885Z","steps":["trace[1840411973] 'process raft request' (duration: 166.352233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:40:33.017064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.706427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:40:33.017102Z","caller":"traceutil/trace.go:171","msg":"trace[1279483807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3723; }","duration":"146.810859ms","start":"2026-04-08T00:40:32.87028Z","end":"2026-04-08T00:40:33.017091Z","steps":["trace[1279483807] 'agreement among raft nodes before linearized reading' (duration: 146.712357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:40:33.017327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.113698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12209"} {"level":"info","ts":"2026-04-08T00:40:33.017397Z","caller":"traceutil/trace.go:171","msg":"trace[628968213] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3723; }","duration":"119.234101ms","start":"2026-04-08T00:40:32.898146Z","end":"2026-04-08T00:40:33.01738Z","steps":["trace[628968213] 'agreement among raft nodes before linearized reading' (duration: 119.021837ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:41:55.334728Z","caller":"traceutil/trace.go:171","msg":"trace[1087080392] transaction","detail":"{read_only:false; response_revision:4661; number_of_response:1; }","duration":"183.817868ms","start":"2026-04-08T00:41:55.150886Z","end":"2026-04-08T00:41:55.334704Z","steps":["trace[1087080392] 'process raft request' (duration: 183.742846ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:41:55.334816Z","caller":"traceutil/trace.go:171","msg":"trace[818901916] linearizableReadLoop","detail":"{readStateIndex:4839; appliedIndex:4838; }","duration":"190.045862ms","start":"2026-04-08T00:41:55.144722Z","end":"2026-04-08T00:41:55.334768Z","steps":["trace[818901916] 'read index received' (duration: 153.739519ms)","trace[818901916] 'applied index is now lower than readState.Index' (duration: 36.296093ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:41:55.334861Z","caller":"traceutil/trace.go:171","msg":"trace[705691984] transaction","detail":"{read_only:false; response_revision:4660; number_of_response:1; }","duration":"207.218581ms","start":"2026-04-08T00:41:55.127611Z","end":"2026-04-08T00:41:55.334829Z","steps":["trace[705691984] 'process raft request' (duration: 170.869117ms)","trace[705691984] 'compare' (duration: 35.939645ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:41:55.33509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.348849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-tls\" ","response":"range_response_count:1 size:3075"} {"level":"info","ts":"2026-04-08T00:41:55.335152Z","caller":"traceutil/trace.go:171","msg":"trace[1394302482] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-tls; range_end:; response_count:1; response_revision:4661; }","duration":"190.448012ms","start":"2026-04-08T00:41:55.144687Z","end":"2026-04-08T00:41:55.335135Z","steps":["trace[1394302482] 'agreement among raft nodes before linearized reading' (duration: 190.256658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:41:55.339724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.934641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/grafana-tls\" ","response":"range_response_count:1 size:2834"} {"level":"info","ts":"2026-04-08T00:41:55.339764Z","caller":"traceutil/trace.go:171","msg":"trace[207589631] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/grafana-tls; range_end:; response_count:1; response_revision:4662; }","duration":"144.016543ms","start":"2026-04-08T00:41:55.195737Z","end":"2026-04-08T00:41:55.339754Z","steps":["trace[207589631] 'agreement among raft nodes before linearized reading' (duration: 143.837429ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:41:59.496396Z","caller":"traceutil/trace.go:171","msg":"trace[1024609563] transaction","detail":"{read_only:false; response_revision:4783; number_of_response:1; }","duration":"139.505989ms","start":"2026-04-08T00:41:59.356862Z","end":"2026-04-08T00:41:59.496368Z","steps":["trace[1024609563] 'process raft request' (duration: 139.367996ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:41:59.502185Z","caller":"traceutil/trace.go:171","msg":"trace[1758410518] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"142.838106ms","start":"2026-04-08T00:41:59.359325Z","end":"2026-04-08T00:41:59.502163Z","steps":["trace[1758410518] 'process raft request' (duration: 142.569149ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:41:59.502245Z","caller":"traceutil/trace.go:171","msg":"trace[1615679378] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"115.711396ms","start":"2026-04-08T00:41:59.386502Z","end":"2026-04-08T00:41:59.502214Z","steps":["trace[1615679378] 'process raft request' (duration: 115.488631ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:00.753166Z","caller":"traceutil/trace.go:171","msg":"trace[1618371899] linearizableReadLoop","detail":"{readStateIndex:5021; appliedIndex:5020; }","duration":"203.588688ms","start":"2026-04-08T00:42:00.54953Z","end":"2026-04-08T00:42:00.753119Z","steps":["trace[1618371899] 'read index received' (duration: 200.833534ms)","trace[1618371899] 'applied index is now lower than readState.Index' (duration: 2.752924ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:00.753197Z","caller":"traceutil/trace.go:171","msg":"trace[1957099512] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"204.845616ms","start":"2026-04-08T00:42:00.548314Z","end":"2026-04-08T00:42:00.75316Z","steps":["trace[1957099512] 'process raft request' (duration: 202.185594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:00.753493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.937925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-9021d878-1eed-4b0e-973c-3342aa9122f5\" ","response":"range_response_count:1 size:1435"} {"level":"warn","ts":"2026-04-08T00:42:00.753597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.846413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-04-08T00:42:00.753615Z","caller":"traceutil/trace.go:171","msg":"trace[1081507817] range","detail":"{range_begin:/registry/persistentvolumes/pvc-9021d878-1eed-4b0e-973c-3342aa9122f5; range_end:; response_count:1; response_revision:4835; }","duration":"204.080529ms","start":"2026-04-08T00:42:00.549489Z","end":"2026-04-08T00:42:00.75357Z","steps":["trace[1081507817] 'agreement among raft nodes before linearized reading' (duration: 203.795522ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:00.753652Z","caller":"traceutil/trace.go:171","msg":"trace[1200731378] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4835; }","duration":"113.981037ms","start":"2026-04-08T00:42:00.639649Z","end":"2026-04-08T00:42:00.75363Z","steps":["trace[1200731378] 'agreement among raft nodes before linearized reading' (duration: 113.712961ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:03.483463Z","caller":"traceutil/trace.go:171","msg":"trace[1668531080] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"240.162525ms","start":"2026-04-08T00:42:03.243281Z","end":"2026-04-08T00:42:03.483443Z","steps":["trace[1668531080] 'process raft request' (duration: 240.021231ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:03.513419Z","caller":"traceutil/trace.go:171","msg":"trace[1417726663] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"141.302471ms","start":"2026-04-08T00:42:03.372071Z","end":"2026-04-08T00:42:03.513373Z","steps":["trace[1417726663] 'process raft request' (duration: 139.519559ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:21.762601Z","caller":"traceutil/trace.go:171","msg":"trace[903795731] transaction","detail":"{read_only:false; response_revision:5314; number_of_response:1; }","duration":"106.113852ms","start":"2026-04-08T00:42:21.656466Z","end":"2026-04-08T00:42:21.76258Z","steps":["trace[903795731] 'process raft request' (duration: 105.963468ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:21.762735Z","caller":"traceutil/trace.go:171","msg":"trace[1933291487] transaction","detail":"{read_only:false; response_revision:5315; number_of_response:1; }","duration":"104.051677ms","start":"2026-04-08T00:42:21.658665Z","end":"2026-04-08T00:42:21.762717Z","steps":["trace[1933291487] 'process raft request' (duration: 103.869471ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:22.004929Z","caller":"traceutil/trace.go:171","msg":"trace[726611803] linearizableReadLoop","detail":"{readStateIndex:5514; appliedIndex:5513; }","duration":"242.225229ms","start":"2026-04-08T00:42:21.762684Z","end":"2026-04-08T00:42:22.004909Z","steps":["trace[726611803] 'read index received' (duration: 242.103326ms)","trace[726611803] 'applied index is now lower than readState.Index' (duration: 121.213µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:22.005014Z","caller":"traceutil/trace.go:171","msg":"trace[1556568491] transaction","detail":"{read_only:false; response_revision:5316; number_of_response:1; }","duration":"249.936614ms","start":"2026-04-08T00:42:21.75507Z","end":"2026-04-08T00:42:22.005006Z","steps":["trace[1556568491] 'process raft request' (duration: 249.701119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:22.005249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.20401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-08T00:42:22.005332Z","caller":"traceutil/trace.go:171","msg":"trace[172946162] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5316; }","duration":"258.298732ms","start":"2026-04-08T00:42:21.747014Z","end":"2026-04-08T00:42:22.005313Z","steps":["trace[172946162] 'agreement among raft nodes before linearized reading' (duration: 258.065587ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:22.052619Z","caller":"traceutil/trace.go:171","msg":"trace[609468922] transaction","detail":"{read_only:false; response_revision:5317; number_of_response:1; }","duration":"284.753468ms","start":"2026-04-08T00:42:21.767826Z","end":"2026-04-08T00:42:22.05258Z","steps":["trace[609468922] 'process raft request' (duration: 284.534113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:22.052752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.309834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3548b2a1-86c7-4ea0-b9e5-8d713c3073f2\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-04-08T00:42:22.052758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.76219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:42:22.052829Z","caller":"traceutil/trace.go:171","msg":"trace[1724152307] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3548b2a1-86c7-4ea0-b9e5-8d713c3073f2; range_end:; response_count:1; response_revision:5318; }","duration":"247.426706ms","start":"2026-04-08T00:42:21.805382Z","end":"2026-04-08T00:42:22.052809Z","steps":["trace[1724152307] 'agreement among raft nodes before linearized reading' (duration: 247.190601ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:22.052843Z","caller":"traceutil/trace.go:171","msg":"trace[212288176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5318; }","duration":"178.896473ms","start":"2026-04-08T00:42:21.873927Z","end":"2026-04-08T00:42:22.052824Z","steps":["trace[212288176] 'agreement among raft nodes before linearized reading' (duration: 178.749269ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:22.052796Z","caller":"traceutil/trace.go:171","msg":"trace[562128134] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"284.857791ms","start":"2026-04-08T00:42:21.767841Z","end":"2026-04-08T00:42:22.052698Z","steps":["trace[562128134] 'process raft request' (duration: 284.655867ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:22.275713Z","caller":"traceutil/trace.go:171","msg":"trace[2136624933] transaction","detail":"{read_only:false; response_revision:5319; number_of_response:1; }","duration":"264.672976ms","start":"2026-04-08T00:42:22.010998Z","end":"2026-04-08T00:42:22.275671Z","steps":["trace[2136624933] 'process raft request' (duration: 225.979604ms)","trace[2136624933] 'compare' (duration: 38.465477ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:22.276215Z","caller":"traceutil/trace.go:171","msg":"trace[1346075104] transaction","detail":"{read_only:false; response_revision:5320; number_of_response:1; }","duration":"264.890951ms","start":"2026-04-08T00:42:22.011243Z","end":"2026-04-08T00:42:22.276134Z","steps":["trace[1346075104] 'process raft request' (duration: 264.353829ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:22.276391Z","caller":"traceutil/trace.go:171","msg":"trace[1965573202] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"264.673876ms","start":"2026-04-08T00:42:22.01169Z","end":"2026-04-08T00:42:22.276363Z","steps":["trace[1965573202] 'process raft request' (duration: 264.283108ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:22.276419Z","caller":"traceutil/trace.go:171","msg":"trace[1948365982] linearizableReadLoop","detail":"{readStateIndex:5520; appliedIndex:5516; }","duration":"222.515526ms","start":"2026-04-08T00:42:22.05388Z","end":"2026-04-08T00:42:22.276396Z","steps":["trace[1948365982] 'read index received' (duration: 182.974015ms)","trace[1948365982] 'applied index is now lower than readState.Index' (duration: 39.539971ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:22.276517Z","caller":"traceutil/trace.go:171","msg":"trace[1829489577] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"260.743997ms","start":"2026-04-08T00:42:22.015699Z","end":"2026-04-08T00:42:22.276443Z","steps":["trace[1829489577] 'process raft request' (duration: 260.505092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:22.276589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.68984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:42:22.276655Z","caller":"traceutil/trace.go:171","msg":"trace[1924003032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5322; }","duration":"222.811153ms","start":"2026-04-08T00:42:22.05383Z","end":"2026-04-08T00:42:22.276641Z","steps":["trace[1924003032] 'agreement among raft nodes before linearized reading' (duration: 222.69998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:22.276948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.673936ms","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-08T00:42:22.277005Z","caller":"traceutil/trace.go:171","msg":"trace[1045707874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5322; }","duration":"154.790378ms","start":"2026-04-08T00:42:22.122203Z","end":"2026-04-08T00:42:22.276994Z","steps":["trace[1045707874] 'agreement among raft nodes before linearized reading' (duration: 154.625635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:22.562239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.061385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:42:22.562352Z","caller":"traceutil/trace.go:171","msg":"trace[653282178] linearizableReadLoop","detail":"{readStateIndex:5522; appliedIndex:5521; }","duration":"186.971394ms","start":"2026-04-08T00:42:22.375362Z","end":"2026-04-08T00:42:22.562334Z","steps":["trace[653282178] 'read index received' (duration: 39.558201ms)","trace[653282178] 'applied index is now lower than readState.Index' (duration: 147.411743ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:22.562509Z","caller":"traceutil/trace.go:171","msg":"trace[333809221] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"280.630646ms","start":"2026-04-08T00:42:22.281859Z","end":"2026-04-08T00:42:22.562489Z","steps":["trace[333809221] 'process raft request' (duration: 133.156701ms)","trace[333809221] 'compare' (duration: 146.897692ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:42:22.56276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.728249ms","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-08T00:42:22.56284Z","caller":"traceutil/trace.go:171","msg":"trace[1429688921] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5324; }","duration":"182.883332ms","start":"2026-04-08T00:42:22.379939Z","end":"2026-04-08T00:42:22.562822Z","steps":["trace[1429688921] 'agreement among raft nodes before linearized reading' (duration: 182.611466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:22.562809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.450116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3548b2a1-86c7-4ea0-b9e5-8d713c3073f2\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-08T00:42:22.56293Z","caller":"traceutil/trace.go:171","msg":"trace[752663910] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3548b2a1-86c7-4ea0-b9e5-8d713c3073f2; range_end:; response_count:1; response_revision:5324; }","duration":"187.63714ms","start":"2026-04-08T00:42:22.375268Z","end":"2026-04-08T00:42:22.562905Z","steps":["trace[752663910] 'agreement among raft nodes before linearized reading' (duration: 187.273412ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:30.675727Z","caller":"traceutil/trace.go:171","msg":"trace[1254195928] linearizableReadLoop","detail":"{readStateIndex:5672; appliedIndex:5671; }","duration":"108.367792ms","start":"2026-04-08T00:42:30.567325Z","end":"2026-04-08T00:42:30.675693Z","steps":["trace[1254195928] 'read index received' (duration: 108.102456ms)","trace[1254195928] 'applied index is now lower than readState.Index' (duration: 264.216µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:30.675905Z","caller":"traceutil/trace.go:171","msg":"trace[307276304] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"165.605862ms","start":"2026-04-08T00:42:30.510284Z","end":"2026-04-08T00:42:30.675889Z","steps":["trace[307276304] 'process raft request' (duration: 165.185413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:30.67625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.913034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-08T00:42:30.67631Z","caller":"traceutil/trace.go:171","msg":"trace[810340410] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5470; }","duration":"109.011417ms","start":"2026-04-08T00:42:30.567282Z","end":"2026-04-08T00:42:30.676294Z","steps":["trace[810340410] 'agreement among raft nodes before linearized reading' (duration: 108.801583ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:34.477991Z","caller":"traceutil/trace.go:171","msg":"trace[221177013] transaction","detail":"{read_only:false; response_revision:5516; number_of_response:1; }","duration":"141.163122ms","start":"2026-04-08T00:42:34.336812Z","end":"2026-04-08T00:42:34.477976Z","steps":["trace[221177013] 'process raft request' (duration: 141.030409ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:34.478111Z","caller":"traceutil/trace.go:171","msg":"trace[2114530791] linearizableReadLoop","detail":"{readStateIndex:5719; appliedIndex:5718; }","duration":"126.979372ms","start":"2026-04-08T00:42:34.350969Z","end":"2026-04-08T00:42:34.477948Z","steps":["trace[2114530791] 'read index received' (duration: 126.844449ms)","trace[2114530791] 'applied index is now lower than readState.Index' (duration: 134.283µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:42:34.478444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.391592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:42:34.478577Z","caller":"traceutil/trace.go:171","msg":"trace[1113847129] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:5516; }","duration":"127.613827ms","start":"2026-04-08T00:42:34.350931Z","end":"2026-04-08T00:42:34.478545Z","steps":["trace[1113847129] 'agreement among raft nodes before linearized reading' (duration: 127.149187ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:24.875373Z","caller":"traceutil/trace.go:171","msg":"trace[1660420055] transaction","detail":"{read_only:false; response_revision:5954; number_of_response:1; }","duration":"103.637687ms","start":"2026-04-08T00:43:24.771706Z","end":"2026-04-08T00:43:24.875344Z","steps":["trace[1660420055] 'process raft request' (duration: 103.016473ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:43.257584Z","caller":"traceutil/trace.go:171","msg":"trace[1670598755] transaction","detail":"{read_only:false; response_revision:6106; number_of_response:1; }","duration":"114.852349ms","start":"2026-04-08T00:43:43.142701Z","end":"2026-04-08T00:43:43.257554Z","steps":["trace[1670598755] 'process raft request' (duration: 51.149693ms)","trace[1670598755] 'compare' (duration: 63.387078ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:43:43.257637Z","caller":"traceutil/trace.go:171","msg":"trace[399527229] transaction","detail":"{read_only:false; response_revision:6107; number_of_response:1; }","duration":"113.886019ms","start":"2026-04-08T00:43:43.143682Z","end":"2026-04-08T00:43:43.257568Z","steps":["trace[399527229] 'process raft request' (duration: 113.730665ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:44.193259Z","caller":"traceutil/trace.go:171","msg":"trace[1494375104] linearizableReadLoop","detail":"{readStateIndex:6327; appliedIndex:6326; }","duration":"100.206189ms","start":"2026-04-08T00:43:44.09302Z","end":"2026-04-08T00:43:44.193227Z","steps":["trace[1494375104] 'read index received' (duration: 100.012695ms)","trace[1494375104] 'applied index is now lower than readState.Index' (duration: 191.914µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:43:44.193367Z","caller":"traceutil/trace.go:171","msg":"trace[1660897604] transaction","detail":"{read_only:false; response_revision:6108; number_of_response:1; }","duration":"100.817843ms","start":"2026-04-08T00:43:44.092508Z","end":"2026-04-08T00:43:44.193326Z","steps":["trace[1660897604] 'process raft request' (duration: 100.507447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:44.193599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.493317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:1 size:8317"} {"level":"info","ts":"2026-04-08T00:43:44.193668Z","caller":"traceutil/trace.go:171","msg":"trace[953685874] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:1; response_revision:6108; }","duration":"100.66249ms","start":"2026-04-08T00:43:44.092988Z","end":"2026-04-08T00:43:44.193651Z","steps":["trace[953685874] 'agreement among raft nodes before linearized reading' (duration: 100.370483ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:45.442985Z","caller":"traceutil/trace.go:171","msg":"trace[1576992544] linearizableReadLoop","detail":"{readStateIndex:6333; appliedIndex:6332; }","duration":"258.763556ms","start":"2026-04-08T00:43:45.184204Z","end":"2026-04-08T00:43:45.442967Z","steps":["trace[1576992544] 'read index received' (duration: 258.597362ms)","trace[1576992544] 'applied index is now lower than readState.Index' (duration: 165.444µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:43:45.443044Z","caller":"traceutil/trace.go:171","msg":"trace[2080577133] transaction","detail":"{read_only:false; response_revision:6114; number_of_response:1; }","duration":"295.110985ms","start":"2026-04-08T00:43:45.147919Z","end":"2026-04-08T00:43:45.44303Z","steps":["trace[2080577133] 'process raft request' (duration: 294.926471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:45.443119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.904518ms","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-08T00:43:45.443143Z","caller":"traceutil/trace.go:171","msg":"trace[1535520048] 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:6114; }","duration":"258.947679ms","start":"2026-04-08T00:43:45.184188Z","end":"2026-04-08T00:43:45.443136Z","steps":["trace[1535520048] 'agreement among raft nodes before linearized reading' (duration: 258.868888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:45.443394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.94244ms","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-08T00:43:45.443487Z","caller":"traceutil/trace.go:171","msg":"trace[2103417118] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6114; }","duration":"181.090034ms","start":"2026-04-08T00:43:45.262376Z","end":"2026-04-08T00:43:45.443466Z","steps":["trace[2103417118] 'agreement among raft nodes before linearized reading' (duration: 180.820128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:45.443394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.834467ms","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-08T00:43:45.443662Z","caller":"traceutil/trace.go:171","msg":"trace[845891561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6114; }","duration":"181.125195ms","start":"2026-04-08T00:43:45.262521Z","end":"2026-04-08T00:43:45.443646Z","steps":["trace[845891561] 'agreement among raft nodes before linearized reading' (duration: 180.706335ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:46.559778Z","caller":"traceutil/trace.go:171","msg":"trace[439424476] transaction","detail":"{read_only:false; response_revision:6120; number_of_response:1; }","duration":"163.324992ms","start":"2026-04-08T00:43:46.396425Z","end":"2026-04-08T00:43:46.55975Z","steps":["trace[439424476] 'process raft request' (duration: 123.029454ms)","trace[439424476] 'compare' (duration: 40.029352ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:43:48.345476Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2975} {"level":"warn","ts":"2026-04-08T00:43:48.346129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.725227ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:5"} {"level":"info","ts":"2026-04-08T00:43:48.346271Z","caller":"traceutil/trace.go:171","msg":"trace[497637958] linearizableReadLoop","detail":"{readStateIndex:6349; appliedIndex:6348; }","duration":"120.829754ms","start":"2026-04-08T00:43:48.22542Z","end":"2026-04-08T00:43:48.34625Z","steps":["trace[497637958] 'read index received' (duration: 13.129536ms)","trace[497637958] 'applied index is now lower than readState.Index' (duration: 107.698998ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:43:48.34632Z","caller":"traceutil/trace.go:171","msg":"trace[1312187234] compact","detail":"{revision:2975; response_revision:6128; }","duration":"145.353778ms","start":"2026-04-08T00:43:48.200934Z","end":"2026-04-08T00:43:48.346288Z","steps":["trace[1312187234] 'process raft request' (duration: 37.530747ms)","trace[1312187234] 'check and update compact revision' (duration: 106.586074ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:43:48.346469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.05251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:1 size:8317"} {"level":"info","ts":"2026-04-08T00:43:48.346566Z","caller":"traceutil/trace.go:171","msg":"trace[2116367394] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:1; response_revision:6128; }","duration":"121.239634ms","start":"2026-04-08T00:43:48.225309Z","end":"2026-04-08T00:43:48.346548Z","steps":["trace[2116367394] 'agreement among raft nodes before linearized reading' (duration: 121.010709ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:48.347826Z","caller":"traceutil/trace.go:171","msg":"trace[442506518] transaction","detail":"{read_only:false; response_revision:6129; number_of_response:1; }","duration":"121.778987ms","start":"2026-04-08T00:43:48.225982Z","end":"2026-04-08T00:43:48.347761Z","steps":["trace[442506518] 'process raft request' (duration: 120.254442ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:48.729353Z","caller":"traceutil/trace.go:171","msg":"trace[1799521746] transaction","detail":"{read_only:false; response_revision:6132; number_of_response:1; }","duration":"120.272363ms","start":"2026-04-08T00:43:48.609026Z","end":"2026-04-08T00:43:48.729299Z","steps":["trace[1799521746] 'process raft request' (duration: 35.567683ms)","trace[1799521746] 'compare' (duration: 59.062262ms)","trace[1799521746] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-controller-manager; req_size:488; } (duration: 25.419913ms)"],"step_count":3} {"level":"info","ts":"2026-04-08T00:43:48.883624Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2975,"took":"536.261042ms","hash":2472359424,"current-db-size-bytes":58040320,"current-db-size":"58 MB","current-db-size-in-use-bytes":33894400,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-08T00:43:48.883742Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2472359424,"revision":2975,"compact-revision":-1} {"level":"warn","ts":"2026-04-08T00:43:48.88417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.764419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:43:48.884505Z","caller":"traceutil/trace.go:171","msg":"trace[625049391] transaction","detail":"{read_only:false; response_revision:6133; number_of_response:1; }","duration":"272.150277ms","start":"2026-04-08T00:43:48.612263Z","end":"2026-04-08T00:43:48.884413Z","steps":["trace[625049391] 'process raft request' (duration: 117.012779ms)","trace[625049391] 'compare' (duration: 102.263345ms)","trace[625049391] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:460; } (duration: 52.33729ms)"],"step_count":3} {"level":"info","ts":"2026-04-08T00:44:03.918099Z","caller":"traceutil/trace.go:171","msg":"trace[307083780] transaction","detail":"{read_only:false; response_revision:6243; number_of_response:1; }","duration":"102.727027ms","start":"2026-04-08T00:44:03.815351Z","end":"2026-04-08T00:44:03.918078Z","steps":["trace[307083780] 'process raft request' (duration: 102.547003ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:04.182109Z","caller":"traceutil/trace.go:171","msg":"trace[105807835] transaction","detail":"{read_only:false; response_revision:6246; number_of_response:1; }","duration":"112.517517ms","start":"2026-04-08T00:44:04.069558Z","end":"2026-04-08T00:44:04.182075Z","steps":["trace[105807835] 'process raft request' (duration: 110.768728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:04.385922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.691566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:44:04.386008Z","caller":"traceutil/trace.go:171","msg":"trace[946890718] linearizableReadLoop","detail":"{readStateIndex:6471; appliedIndex:6470; }","duration":"162.604527ms","start":"2026-04-08T00:44:04.223391Z","end":"2026-04-08T00:44:04.385995Z","steps":["trace[946890718] 'read index received' (duration: 59.691146ms)","trace[946890718] 'applied index is now lower than readState.Index' (duration: 102.912621ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:44:04.386053Z","caller":"traceutil/trace.go:171","msg":"trace[433049559] transaction","detail":"{read_only:false; response_revision:6247; number_of_response:1; }","duration":"165.386999ms","start":"2026-04-08T00:44:04.220635Z","end":"2026-04-08T00:44:04.386022Z","steps":["trace[433049559] 'process raft request' (duration: 62.521429ms)","trace[433049559] 'compare' (duration: 102.586874ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:44:04.386129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.737119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-api-68ff4956c4-s9fcf\" ","response":"range_response_count:1 size:12150"} {"level":"info","ts":"2026-04-08T00:44:04.386157Z","caller":"traceutil/trace.go:171","msg":"trace[962457502] range","detail":"{range_begin:/registry/pods/openstack/keystone-api-68ff4956c4-s9fcf; range_end:; response_count:1; response_revision:6247; }","duration":"162.783781ms","start":"2026-04-08T00:44:04.223367Z","end":"2026-04-08T00:44:04.38615Z","steps":["trace[962457502] 'agreement among raft nodes before linearized reading' (duration: 162.661878ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:04.545672Z","caller":"traceutil/trace.go:171","msg":"trace[2027645062] transaction","detail":"{read_only:false; response_revision:6248; number_of_response:1; }","duration":"147.244041ms","start":"2026-04-08T00:44:04.398396Z","end":"2026-04-08T00:44:04.54564Z","steps":["trace[2027645062] 'process raft request' (duration: 91.76789ms)","trace[2027645062] 'compare' (duration: 55.347888ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:44:04.568254Z","caller":"traceutil/trace.go:171","msg":"trace[1119678495] transaction","detail":"{read_only:false; response_revision:6252; number_of_response:1; }","duration":"141.741836ms","start":"2026-04-08T00:44:04.426489Z","end":"2026-04-08T00:44:04.568231Z","steps":["trace[1119678495] 'process raft request' (duration: 141.700186ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:04.568427Z","caller":"traceutil/trace.go:171","msg":"trace[2125240917] transaction","detail":"{read_only:false; response_revision:6249; number_of_response:1; }","duration":"169.514813ms","start":"2026-04-08T00:44:04.398886Z","end":"2026-04-08T00:44:04.568401Z","steps":["trace[2125240917] 'process raft request' (duration: 169.104644ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:04.568729Z","caller":"traceutil/trace.go:171","msg":"trace[1744559581] transaction","detail":"{read_only:false; response_revision:6250; number_of_response:1; }","duration":"151.061576ms","start":"2026-04-08T00:44:04.417611Z","end":"2026-04-08T00:44:04.568672Z","steps":["trace[1744559581] 'process raft request' (duration: 150.493194ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:04.568773Z","caller":"traceutil/trace.go:171","msg":"trace[908640113] transaction","detail":"{read_only:false; response_revision:6251; number_of_response:1; }","duration":"143.592908ms","start":"2026-04-08T00:44:04.425149Z","end":"2026-04-08T00:44:04.568742Z","steps":["trace[908640113] 'process raft request' (duration: 143.007085ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:04.704046Z","caller":"traceutil/trace.go:171","msg":"trace[1245000055] transaction","detail":"{read_only:false; response_revision:6253; number_of_response:1; }","duration":"130.041832ms","start":"2026-04-08T00:44:04.573966Z","end":"2026-04-08T00:44:04.704008Z","steps":["trace[1245000055] 'process raft request' (duration: 125.375797ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:04.888893Z","caller":"traceutil/trace.go:171","msg":"trace[809437100] transaction","detail":"{read_only:false; response_revision:6256; number_of_response:1; }","duration":"177.136924ms","start":"2026-04-08T00:44:04.711723Z","end":"2026-04-08T00:44:04.88886Z","steps":["trace[809437100] 'process raft request' (duration: 177.045362ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:04.888896Z","caller":"traceutil/trace.go:171","msg":"trace[940622842] transaction","detail":"{read_only:false; response_revision:6255; number_of_response:1; }","duration":"179.791824ms","start":"2026-04-08T00:44:04.709068Z","end":"2026-04-08T00:44:04.888859Z","steps":["trace[940622842] 'process raft request' (duration: 179.505297ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:05.327509Z","caller":"traceutil/trace.go:171","msg":"trace[1529918977] transaction","detail":"{read_only:false; response_revision:6261; number_of_response:1; }","duration":"104.072616ms","start":"2026-04-08T00:44:05.223406Z","end":"2026-04-08T00:44:05.327479Z","steps":["trace[1529918977] 'process raft request' (duration: 103.864992ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:07.767241Z","caller":"traceutil/trace.go:171","msg":"trace[1475280735] linearizableReadLoop","detail":"{readStateIndex:6504; appliedIndex:6503; }","duration":"138.456953ms","start":"2026-04-08T00:44:07.628753Z","end":"2026-04-08T00:44:07.76721Z","steps":["trace[1475280735] 'read index received' (duration: 137.778558ms)","trace[1475280735] 'applied index is now lower than readState.Index' (duration: 677.445µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:44:07.76791Z","caller":"traceutil/trace.go:171","msg":"trace[76601721] transaction","detail":"{read_only:false; response_revision:6279; number_of_response:1; }","duration":"164.106971ms","start":"2026-04-08T00:44:07.603788Z","end":"2026-04-08T00:44:07.767895Z","steps":["trace[76601721] 'process raft request' (duration: 163.024367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:07.768287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.514166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-08T00:44:07.768339Z","caller":"traceutil/trace.go:171","msg":"trace[1806542600] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6279; }","duration":"139.642479ms","start":"2026-04-08T00:44:07.628681Z","end":"2026-04-08T00:44:07.768323Z","steps":["trace[1806542600] 'agreement among raft nodes before linearized reading' (duration: 139.448194ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:13.859426Z","caller":"traceutil/trace.go:171","msg":"trace[669919830] transaction","detail":"{read_only:false; response_revision:8115; number_of_response:1; }","duration":"255.91126ms","start":"2026-04-08T00:48:13.603476Z","end":"2026-04-08T00:48:13.859387Z","steps":["trace[669919830] 'process raft request' (duration: 194.884731ms)","trace[669919830] 'compare' (duration: 60.829904ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:48:13.859588Z","caller":"traceutil/trace.go:171","msg":"trace[395039563] transaction","detail":"{read_only:false; response_revision:8116; number_of_response:1; }","duration":"202.574642ms","start":"2026-04-08T00:48:13.656979Z","end":"2026-04-08T00:48:13.859554Z","steps":["trace[395039563] 'process raft request' (duration: 202.369038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:25.29137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.981565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:48:25.29149Z","caller":"traceutil/trace.go:171","msg":"trace[650684279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8172; }","duration":"419.133979ms","start":"2026-04-08T00:48:24.872319Z","end":"2026-04-08T00:48:25.291453Z","steps":["trace[650684279] 'range keys from in-memory index tree' (duration: 418.879633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:25.291536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:24.872305Z","time spent":"419.221561ms","remote":"127.0.0.1:50456","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-08T00:48:25.291514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.205878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-08T00:48:25.291627Z","caller":"traceutil/trace.go:171","msg":"trace[1462556052] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8172; }","duration":"212.391322ms","start":"2026-04-08T00:48:25.079212Z","end":"2026-04-08T00:48:25.291603Z","steps":["trace[1462556052] 'range keys from in-memory index tree' (duration: 211.906692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:25.29135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.436597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-08T00:48:25.291748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.358295ms","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-08T00:48:25.291836Z","caller":"traceutil/trace.go:171","msg":"trace[1701955814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8172; }","duration":"209.460997ms","start":"2026-04-08T00:48:25.082357Z","end":"2026-04-08T00:48:25.291818Z","steps":["trace[1701955814] 'range keys from in-memory index tree' (duration: 209.11754ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:25.291754Z","caller":"traceutil/trace.go:171","msg":"trace[240633470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8172; }","duration":"213.864166ms","start":"2026-04-08T00:48:25.077854Z","end":"2026-04-08T00:48:25.291719Z","steps":["trace[240633470] 'range keys from in-memory index tree' (duration: 213.179711ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:25.414173Z","caller":"traceutil/trace.go:171","msg":"trace[161944952] transaction","detail":"{read_only:false; response_revision:8174; number_of_response:1; }","duration":"117.387922ms","start":"2026-04-08T00:48:25.296751Z","end":"2026-04-08T00:48:25.414139Z","steps":["trace[161944952] 'process raft request' (duration: 102.314344ms)","trace[161944952] 'compare' (duration: 14.882243ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:48:25.414462Z","caller":"traceutil/trace.go:171","msg":"trace[1187354958] transaction","detail":"{read_only:false; response_revision:8175; number_of_response:1; }","duration":"117.672099ms","start":"2026-04-08T00:48:25.296779Z","end":"2026-04-08T00:48:25.414451Z","steps":["trace[1187354958] 'process raft request' (duration: 117.30964ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:26.817288Z","caller":"traceutil/trace.go:171","msg":"trace[1611733653] transaction","detail":"{read_only:false; response_revision:8179; number_of_response:1; }","duration":"478.579382ms","start":"2026-04-08T00:48:26.338678Z","end":"2026-04-08T00:48:26.817258Z","steps":["trace[1611733653] 'process raft request' (duration: 478.419498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:26.817472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:26.338647Z","time spent":"478.731876ms","remote":"127.0.0.1:50736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:48:26.817702Z","caller":"traceutil/trace.go:171","msg":"trace[340529170] linearizableReadLoop","detail":"{readStateIndex:8463; appliedIndex:8463; }","duration":"467.81046ms","start":"2026-04-08T00:48:26.349859Z","end":"2026-04-08T00:48:26.81767Z","steps":["trace[340529170] 'read index received' (duration: 467.80069ms)","trace[340529170] 'applied index is now lower than readState.Index' (duration: 8.07µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:48:26.818056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.156219ms","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-08T00:48:26.818186Z","caller":"traceutil/trace.go:171","msg":"trace[1041640436] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8179; }","duration":"468.318853ms","start":"2026-04-08T00:48:26.349837Z","end":"2026-04-08T00:48:26.818156Z","steps":["trace[1041640436] 'agreement among raft nodes before linearized reading' (duration: 467.934663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:26.818251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:26.349829Z","time spent":"468.407915ms","remote":"127.0.0.1:50736","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-08T00:48:27.143957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.837134ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-08T00:48:27.144376Z","caller":"traceutil/trace.go:171","msg":"trace[580032390] linearizableReadLoop","detail":"{readStateIndex:8464; appliedIndex:8463; }","duration":"326.519301ms","start":"2026-04-08T00:48:26.817796Z","end":"2026-04-08T00:48:27.144315Z","steps":["trace[580032390] 'read index received' (duration: 133.8175ms)","trace[580032390] 'applied index is now lower than readState.Index' (duration: 192.691841ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:48:27.144577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.950867ms","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-08T00:48:27.144672Z","caller":"traceutil/trace.go:171","msg":"trace[1999920988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8179; }","duration":"387.09899ms","start":"2026-04-08T00:48:26.757548Z","end":"2026-04-08T00:48:27.144647Z","steps":["trace[1999920988] 'agreement among raft nodes before linearized reading' (duration: 386.785923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:27.144718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:26.757529Z","time spent":"387.179572ms","remote":"127.0.0.1:50736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T00:48:27.144724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.592038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:48:27.144776Z","caller":"traceutil/trace.go:171","msg":"trace[88291517] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:8179; }","duration":"423.735122ms","start":"2026-04-08T00:48:26.721025Z","end":"2026-04-08T00:48:27.14476Z","steps":["trace[88291517] 'agreement among raft nodes before linearized reading' (duration: 423.587329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:27.144833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:26.720998Z","time spent":"423.817623ms","remote":"127.0.0.1:50784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-04-08T00:48:27.14494Z","caller":"traceutil/trace.go:171","msg":"trace[66175096] transaction","detail":"{read_only:false; response_revision:8180; number_of_response:1; }","duration":"322.767727ms","start":"2026-04-08T00:48:26.822139Z","end":"2026-04-08T00:48:27.144907Z","steps":["trace[66175096] 'process raft request' (duration: 322.095462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:27.145106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:26.822097Z","time spent":"322.91549ms","remote":"127.0.0.1:50736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:48:27.145598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.930082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:48:27.145713Z","caller":"traceutil/trace.go:171","msg":"trace[483755565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8180; }","duration":"274.105807ms","start":"2026-04-08T00:48:26.87158Z","end":"2026-04-08T00:48:27.145686Z","steps":["trace[483755565] 'agreement among raft nodes before linearized reading' (duration: 273.290688ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:27.583118Z","caller":"traceutil/trace.go:171","msg":"trace[1459034553] transaction","detail":"{read_only:false; response_revision:8183; number_of_response:1; }","duration":"160.102859ms","start":"2026-04-08T00:48:27.422971Z","end":"2026-04-08T00:48:27.583073Z","steps":["trace[1459034553] 'process raft request' (duration: 134.115736ms)","trace[1459034553] 'compare' (duration: 25.711657ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:48:27.677857Z","caller":"traceutil/trace.go:171","msg":"trace[850147320] linearizableReadLoop","detail":"{readStateIndex:8469; appliedIndex:8467; }","duration":"252.209907ms","start":"2026-04-08T00:48:27.42563Z","end":"2026-04-08T00:48:27.677839Z","steps":["trace[850147320] 'read index received' (duration: 131.478349ms)","trace[850147320] 'applied index is now lower than readState.Index' (duration: 120.730337ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:48:27.677984Z","caller":"traceutil/trace.go:171","msg":"trace[1817847783] transaction","detail":"{read_only:false; response_revision:8184; number_of_response:1; }","duration":"254.034677ms","start":"2026-04-08T00:48:27.423907Z","end":"2026-04-08T00:48:27.677942Z","steps":["trace[1817847783] 'process raft request' (duration: 253.76995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:27.678099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.439181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ml7k8\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T00:48:27.678139Z","caller":"traceutil/trace.go:171","msg":"trace[490234487] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ml7k8; range_end:; response_count:1; response_revision:8184; }","duration":"252.517783ms","start":"2026-04-08T00:48:27.425611Z","end":"2026-04-08T00:48:27.678129Z","steps":["trace[490234487] 'agreement among raft nodes before linearized reading' (duration: 252.326709ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:27.777214Z","caller":"traceutil/trace.go:171","msg":"trace[1393810753] transaction","detail":"{read_only:false; response_revision:8185; number_of_response:1; }","duration":"141.444173ms","start":"2026-04-08T00:48:27.635732Z","end":"2026-04-08T00:48:27.777176Z","steps":["trace[1393810753] 'process raft request' (duration: 129.814022ms)","trace[1393810753] 'compare' (duration: 11.368455ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:48:27.777355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.702956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:48:27.777407Z","caller":"traceutil/trace.go:171","msg":"trace[325105774] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8186; }","duration":"314.812048ms","start":"2026-04-08T00:48:27.462581Z","end":"2026-04-08T00:48:27.777393Z","steps":["trace[325105774] 'agreement among raft nodes before linearized reading' (duration: 314.695216ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:27.777218Z","caller":"traceutil/trace.go:171","msg":"trace[936018557] transaction","detail":"{read_only:false; response_revision:8186; number_of_response:1; }","duration":"103.592203ms","start":"2026-04-08T00:48:27.673607Z","end":"2026-04-08T00:48:27.777199Z","steps":["trace[936018557] 'process raft request' (duration: 103.472351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:27.777447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:27.462567Z","time spent":"314.86538ms","remote":"127.0.0.1:58192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-04-08T00:48:28.037356Z","caller":"traceutil/trace.go:171","msg":"trace[748501496] transaction","detail":"{read_only:false; response_revision:8188; number_of_response:1; }","duration":"103.519641ms","start":"2026-04-08T00:48:27.933643Z","end":"2026-04-08T00:48:28.037162Z","steps":["trace[748501496] 'process raft request' (duration: 103.378868ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:48.35238Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6128} {"level":"info","ts":"2026-04-08T00:48:48.454496Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6128,"took":"99.109362ms","hash":726227700,"current-db-size-bytes":58040320,"current-db-size":"58 MB","current-db-size-in-use-bytes":23031808,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-08T00:48:48.454589Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":726227700,"revision":6128,"compact-revision":2975} {"level":"info","ts":"2026-04-08T00:48:55.469844Z","caller":"traceutil/trace.go:171","msg":"trace[1835695928] linearizableReadLoop","detail":"{readStateIndex:8681; appliedIndex:8680; }","duration":"126.865365ms","start":"2026-04-08T00:48:55.342944Z","end":"2026-04-08T00:48:55.469809Z","steps":["trace[1835695928] 'read index received' (duration: 126.66572ms)","trace[1835695928] 'applied index is now lower than readState.Index' (duration: 198.085µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:48:55.469925Z","caller":"traceutil/trace.go:171","msg":"trace[478214082] transaction","detail":"{read_only:false; response_revision:8387; number_of_response:1; }","duration":"159.287641ms","start":"2026-04-08T00:48:55.310588Z","end":"2026-04-08T00:48:55.469875Z","steps":["trace[478214082] 'process raft request' (duration: 159.029495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:55.470036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.066789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:48:55.470118Z","caller":"traceutil/trace.go:171","msg":"trace[516506904] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:8387; }","duration":"127.217782ms","start":"2026-04-08T00:48:55.342878Z","end":"2026-04-08T00:48:55.470095Z","steps":["trace[516506904] 'agreement among raft nodes before linearized reading' (duration: 127.068399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:55.968758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.940454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:48:55.968903Z","caller":"traceutil/trace.go:171","msg":"trace[916599072] linearizableReadLoop","detail":"{readStateIndex:8682; appliedIndex:8681; }","duration":"419.834335ms","start":"2026-04-08T00:48:55.549046Z","end":"2026-04-08T00:48:55.968881Z","steps":["trace[916599072] 'read index received' (duration: 169.627574ms)","trace[916599072] 'applied index is now lower than readState.Index' (duration: 250.205551ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:48:55.968991Z","caller":"traceutil/trace.go:171","msg":"trace[1900745689] transaction","detail":"{read_only:false; response_revision:8388; number_of_response:1; }","duration":"465.669663ms","start":"2026-04-08T00:48:55.503308Z","end":"2026-04-08T00:48:55.968978Z","steps":["trace[1900745689] 'process raft request' (duration: 215.3927ms)","trace[1900745689] 'compare' (duration: 249.74933ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:48:55.969063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:55.503278Z","time spent":"465.752455ms","remote":"127.0.0.1:50736","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-08T00:48:55.969816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.756385ms","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-08T00:48:55.969927Z","caller":"traceutil/trace.go:171","msg":"trace[1295332916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8388; }","duration":"420.928658ms","start":"2026-04-08T00:48:55.548973Z","end":"2026-04-08T00:48:55.969902Z","steps":["trace[1295332916] 'agreement among raft nodes before linearized reading' (duration: 420.489199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:55.969994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:55.548944Z","time spent":"421.031411ms","remote":"127.0.0.1:50736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-08T00:48:56.206508Z","caller":"traceutil/trace.go:171","msg":"trace[1421274931] linearizableReadLoop","detail":"{readStateIndex:8683; appliedIndex:8682; }","duration":"237.545486ms","start":"2026-04-08T00:48:55.968932Z","end":"2026-04-08T00:48:56.206478Z","steps":["trace[1421274931] 'read index received' (duration: 167.490205ms)","trace[1421274931] 'applied index is now lower than readState.Index' (duration: 70.053941ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:48:56.206681Z","caller":"traceutil/trace.go:171","msg":"trace[1321822482] transaction","detail":"{read_only:false; response_revision:8389; number_of_response:1; }","duration":"338.026839ms","start":"2026-04-08T00:48:55.868616Z","end":"2026-04-08T00:48:56.206643Z","steps":["trace[1321822482] 'process raft request' (duration: 267.705813ms)","trace[1321822482] 'compare' (duration: 69.826465ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:48:56.20684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:55.868582Z","time spent":"338.142121ms","remote":"127.0.0.1:50736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:48:56.20687Z","caller":"traceutil/trace.go:171","msg":"trace[2071398463] transaction","detail":"{read_only:false; response_revision:8390; number_of_response:1; }","duration":"230.954158ms","start":"2026-04-08T00:48:55.975877Z","end":"2026-04-08T00:48:56.206831Z","steps":["trace[2071398463] 'process raft request' (duration: 230.548579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:56.20689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.394246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:48:56.207112Z","caller":"traceutil/trace.go:171","msg":"trace[466334615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8390; }","duration":"333.692383ms","start":"2026-04-08T00:48:55.873404Z","end":"2026-04-08T00:48:56.207096Z","steps":["trace[466334615] 'agreement among raft nodes before linearized reading' (duration: 333.374735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:56.207153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.565063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ml7k8\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-04-08T00:48:56.207185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:55.873377Z","time spent":"333.770825ms","remote":"127.0.0.1:50456","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-08T00:48:56.207205Z","caller":"traceutil/trace.go:171","msg":"trace[2032700288] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ml7k8; range_end:; response_count:1; response_revision:8390; }","duration":"252.644445ms","start":"2026-04-08T00:48:55.954543Z","end":"2026-04-08T00:48:56.207187Z","steps":["trace[2032700288] 'agreement among raft nodes before linearized reading' (duration: 252.407499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:56.207212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.773769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-08T00:48:56.207438Z","caller":"traceutil/trace.go:171","msg":"trace[1893162183] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8390; }","duration":"381.073215ms","start":"2026-04-08T00:48:55.826339Z","end":"2026-04-08T00:48:56.207413Z","steps":["trace[1893162183] 'agreement among raft nodes before linearized reading' (duration: 380.649976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:56.207512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:55.826315Z","time spent":"381.184497ms","remote":"127.0.0.1:50736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-08T00:48:56.20757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.06623ms","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":"warn","ts":"2026-04-08T00:48:56.207588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.789462ms","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-08T00:48:56.207623Z","caller":"traceutil/trace.go:171","msg":"trace[1013897753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8391; }","duration":"497.878555ms","start":"2026-04-08T00:48:55.709737Z","end":"2026-04-08T00:48:56.207616Z","steps":["trace[1013897753] 'agreement among raft nodes before linearized reading' (duration: 497.374764ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:56.207628Z","caller":"traceutil/trace.go:171","msg":"trace[1775260980] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:8391; }","duration":"577.158952ms","start":"2026-04-08T00:48:55.630454Z","end":"2026-04-08T00:48:56.207613Z","steps":["trace[1775260980] 'agreement among raft nodes before linearized reading' (duration: 576.146808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:56.207646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:55.709708Z","time spent":"497.932215ms","remote":"127.0.0.1:50736","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":"warn","ts":"2026-04-08T00:48:56.207683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:55.630439Z","time spent":"577.220954ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-08T00:48:56.252009Z","caller":"traceutil/trace.go:171","msg":"trace[377857362] transaction","detail":"{read_only:false; response_revision:8392; number_of_response:1; }","duration":"108.401521ms","start":"2026-04-08T00:48:56.143563Z","end":"2026-04-08T00:48:56.251964Z","steps":["trace[377857362] 'process raft request' (duration: 108.099923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:56.391263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.386033ms","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-08T00:48:56.391429Z","caller":"traceutil/trace.go:171","msg":"trace[911847192] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8392; }","duration":"134.583148ms","start":"2026-04-08T00:48:56.256822Z","end":"2026-04-08T00:48:56.391405Z","steps":["trace[911847192] 'range keys from in-memory index tree' (duration: 134.355183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:56.391665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.756793ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-08T00:48:56.392118Z","caller":"traceutil/trace.go:171","msg":"trace[1961520373] linearizableReadLoop","detail":"{readStateIndex:8687; appliedIndex:8685; }","duration":"183.442124ms","start":"2026-04-08T00:48:56.208645Z","end":"2026-04-08T00:48:56.392088Z","steps":["trace[1961520373] 'read index received' (duration: 43.094146ms)","trace[1961520373] 'applied index is now lower than readState.Index' (duration: 140.344548ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:48:56.392312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.618918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:48:56.392423Z","caller":"traceutil/trace.go:171","msg":"trace[1043852534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8392; }","duration":"183.837552ms","start":"2026-04-08T00:48:56.208558Z","end":"2026-04-08T00:48:56.392395Z","steps":["trace[1043852534] 'agreement among raft nodes before linearized reading' (duration: 183.645208ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:56.392746Z","caller":"traceutil/trace.go:171","msg":"trace[1712563875] transaction","detail":"{read_only:false; response_revision:8393; number_of_response:1; }","duration":"179.653529ms","start":"2026-04-08T00:48:56.21298Z","end":"2026-04-08T00:48:56.392634Z","steps":["trace[1712563875] 'process raft request' (duration: 179.033525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:56.39352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.963005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228579"} {"level":"info","ts":"2026-04-08T00:48:56.392802Z","caller":"traceutil/trace.go:171","msg":"trace[313399456] transaction","detail":"{read_only:false; response_revision:8394; number_of_response:1; }","duration":"179.409673ms","start":"2026-04-08T00:48:56.213377Z","end":"2026-04-08T00:48:56.392787Z","steps":["trace[313399456] 'process raft request' (duration: 179.180158ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:56.393595Z","caller":"traceutil/trace.go:171","msg":"trace[1526609497] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8394; }","duration":"180.067248ms","start":"2026-04-08T00:48:56.213509Z","end":"2026-04-08T00:48:56.393576Z","steps":["trace[1526609497] 'agreement among raft nodes before linearized reading' (duration: 179.26684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:08.752119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.340669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:49:08.752275Z","caller":"traceutil/trace.go:171","msg":"trace[663421173] transaction","detail":"{read_only:false; response_revision:8491; number_of_response:1; }","duration":"560.591021ms","start":"2026-04-08T00:49:08.191656Z","end":"2026-04-08T00:49:08.752247Z","steps":["trace[663421173] 'process raft request' (duration: 296.015037ms)","trace[663421173] 'compare' (duration: 263.989261ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:49:08.752342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:08.191642Z","time spent":"560.670772ms","remote":"127.0.0.1:50632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4633,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:49:08.755571Z","caller":"traceutil/trace.go:171","msg":"trace[1607622903] linearizableReadLoop","detail":"{readStateIndex:8793; appliedIndex:8789; }","duration":"479.887881ms","start":"2026-04-08T00:49:08.275659Z","end":"2026-04-08T00:49:08.755547Z","steps":["trace[1607622903] 'read index received' (duration: 211.972773ms)","trace[1607622903] 'applied index is now lower than readState.Index' (duration: 267.914108ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:49:08.755673Z","caller":"traceutil/trace.go:171","msg":"trace[55315919] transaction","detail":"{read_only:false; response_revision:8493; number_of_response:1; }","duration":"510.761333ms","start":"2026-04-08T00:49:08.244902Z","end":"2026-04-08T00:49:08.755663Z","steps":["trace[55315919] 'process raft request' (duration: 510.507188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:08.755747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:08.244878Z","time spent":"510.824044ms","remote":"127.0.0.1:50736","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-08T00:49:08.75595Z","caller":"traceutil/trace.go:171","msg":"trace[1558276667] transaction","detail":"{read_only:false; response_revision:8495; number_of_response:1; }","duration":"436.140801ms","start":"2026-04-08T00:49:08.319758Z","end":"2026-04-08T00:49:08.755899Z","steps":["trace[1558276667] 'process raft request' (duration: 435.752682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:08.756034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.377761ms","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-08T00:49:08.756068Z","caller":"traceutil/trace.go:171","msg":"trace[145140822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8495; }","duration":"480.434613ms","start":"2026-04-08T00:49:08.275623Z","end":"2026-04-08T00:49:08.756058Z","steps":["trace[145140822] 'agreement among raft nodes before linearized reading' (duration: 480.2957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:08.756106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:08.275612Z","time spent":"480.488054ms","remote":"127.0.0.1:50736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T00:49:08.756171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:08.319735Z","time spent":"436.330694ms","remote":"127.0.0.1:50736","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-08T00:49:08.756031Z","caller":"traceutil/trace.go:171","msg":"trace[731057455] transaction","detail":"{read_only:false; response_revision:8494; number_of_response:1; }","duration":"504.839651ms","start":"2026-04-08T00:49:08.251148Z","end":"2026-04-08T00:49:08.755988Z","steps":["trace[731057455] 'process raft request' (duration: 504.325759ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:49:08.756188Z","caller":"traceutil/trace.go:171","msg":"trace[196404019] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8492; }","duration":"563.950885ms","start":"2026-04-08T00:49:08.192169Z","end":"2026-04-08T00:49:08.75612Z","steps":["trace[196404019] 'process raft request' (duration: 563.138048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:08.756523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:08.251128Z","time spent":"505.122076ms","remote":"127.0.0.1:50736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:49:08.756563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:08.192155Z","time spent":"564.200762ms","remote":"127.0.0.1:58398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-04-08T00:49:08.762547Z","caller":"traceutil/trace.go:171","msg":"trace[1369910908] transaction","detail":"{read_only:false; response_revision:8496; number_of_response:1; }","duration":"146.639638ms","start":"2026-04-08T00:49:08.615893Z","end":"2026-04-08T00:49:08.762532Z","steps":["trace[1369910908] 'process raft request' (duration: 146.374302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:08.762761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.38967ms","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":"warn","ts":"2026-04-08T00:49:08.762765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.44745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ml7k8\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T00:49:08.762808Z","caller":"traceutil/trace.go:171","msg":"trace[808436666] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8497; }","duration":"297.480252ms","start":"2026-04-08T00:49:08.465316Z","end":"2026-04-08T00:49:08.762797Z","steps":["trace[808436666] 'agreement among raft nodes before linearized reading' (duration: 297.285136ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:49:08.762852Z","caller":"traceutil/trace.go:171","msg":"trace[1740472814] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ml7k8; range_end:; response_count:1; response_revision:8497; }","duration":"289.548004ms","start":"2026-04-08T00:49:08.473285Z","end":"2026-04-08T00:49:08.762834Z","steps":["trace[1740472814] 'agreement among raft nodes before linearized reading' (duration: 289.300597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:08.762916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.829788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-08T00:49:08.762951Z","caller":"traceutil/trace.go:171","msg":"trace[83291166] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8497; }","duration":"309.91646ms","start":"2026-04-08T00:49:08.453024Z","end":"2026-04-08T00:49:08.76294Z","steps":["trace[83291166] 'agreement among raft nodes before linearized reading' (duration: 309.848978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:08.762966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:08.452986Z","time spent":"309.976501ms","remote":"127.0.0.1:50736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-08T00:49:09.220183Z","caller":"traceutil/trace.go:171","msg":"trace[497359561] transaction","detail":"{read_only:false; response_revision:8503; number_of_response:1; }","duration":"113.988577ms","start":"2026-04-08T00:49:09.106165Z","end":"2026-04-08T00:49:09.220153Z","steps":["trace[497359561] 'process raft request' (duration: 113.796952ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:49:09.759391Z","caller":"traceutil/trace.go:171","msg":"trace[1611144491] linearizableReadLoop","detail":"{readStateIndex:8804; appliedIndex:8803; }","duration":"224.625496ms","start":"2026-04-08T00:49:09.534721Z","end":"2026-04-08T00:49:09.759347Z","steps":["trace[1611144491] 'read index received' (duration: 224.539804ms)","trace[1611144491] 'applied index is now lower than readState.Index' (duration: 84.522µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:49:09.759425Z","caller":"traceutil/trace.go:171","msg":"trace[768635642] transaction","detail":"{read_only:false; response_revision:8505; number_of_response:1; }","duration":"408.92778ms","start":"2026-04-08T00:49:09.350461Z","end":"2026-04-08T00:49:09.759389Z","steps":["trace[768635642] 'process raft request' (duration: 408.692375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:09.759625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:09.350436Z","time spent":"409.089713ms","remote":"127.0.0.1:41398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5857,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:49:09.759685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.946964ms","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-08T00:49:09.75979Z","caller":"traceutil/trace.go:171","msg":"trace[560452947] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8505; }","duration":"225.124177ms","start":"2026-04-08T00:49:09.534644Z","end":"2026-04-08T00:49:09.759768Z","steps":["trace[560452947] 'agreement among raft nodes before linearized reading' (duration: 224.845291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:09.768347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.679038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:49:09.768438Z","caller":"traceutil/trace.go:171","msg":"trace[1130590060] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:8506; }","duration":"154.824321ms","start":"2026-04-08T00:49:09.613592Z","end":"2026-04-08T00:49:09.768416Z","steps":["trace[1130590060] 'agreement among raft nodes before linearized reading' (duration: 154.667898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:10.479979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.569309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3173"} {"level":"info","ts":"2026-04-08T00:49:10.480092Z","caller":"traceutil/trace.go:171","msg":"trace[1261918172] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:8511; }","duration":"131.744794ms","start":"2026-04-08T00:49:10.348322Z","end":"2026-04-08T00:49:10.480067Z","steps":["trace[1261918172] 'range keys from in-memory index tree' (duration: 131.305753ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:49:10.964195Z","caller":"traceutil/trace.go:171","msg":"trace[21966411] linearizableReadLoop","detail":"{readStateIndex:8814; appliedIndex:8811; }","duration":"168.779084ms","start":"2026-04-08T00:49:10.795384Z","end":"2026-04-08T00:49:10.964163Z","steps":["trace[21966411] 'read index received' (duration: 30.184607ms)","trace[21966411] 'applied index is now lower than readState.Index' (duration: 138.593337ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:49:10.964251Z","caller":"traceutil/trace.go:171","msg":"trace[2037191130] transaction","detail":"{read_only:false; response_revision:8515; number_of_response:1; }","duration":"201.072559ms","start":"2026-04-08T00:49:10.76314Z","end":"2026-04-08T00:49:10.964212Z","steps":["trace[2037191130] 'process raft request' (duration: 200.973846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:10.964497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.080591ms","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-08T00:49:10.964541Z","caller":"traceutil/trace.go:171","msg":"trace[65255213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8515; }","duration":"169.211244ms","start":"2026-04-08T00:49:10.795319Z","end":"2026-04-08T00:49:10.964531Z","steps":["trace[65255213] 'agreement among raft nodes before linearized reading' (duration: 169.020069ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:49:10.964298Z","caller":"traceutil/trace.go:171","msg":"trace[650293772] transaction","detail":"{read_only:false; response_revision:8514; number_of_response:1; }","duration":"202.02928ms","start":"2026-04-08T00:49:10.762208Z","end":"2026-04-08T00:49:10.964237Z","steps":["trace[650293772] 'process raft request' (duration: 170.385211ms)","trace[650293772] 'compare' (duration: 31.386883ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:49:10.964642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.468369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ml7k8\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-04-08T00:49:10.964672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.862831ms","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-08T00:49:10.964745Z","caller":"traceutil/trace.go:171","msg":"trace[1059475752] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8515; }","duration":"149.999304ms","start":"2026-04-08T00:49:10.814729Z","end":"2026-04-08T00:49:10.964729Z","steps":["trace[1059475752] 'agreement among raft nodes before linearized reading' (duration: 149.748227ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:49:10.964689Z","caller":"traceutil/trace.go:171","msg":"trace[1012841107] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ml7k8; range_end:; response_count:1; response_revision:8515; }","duration":"144.553132ms","start":"2026-04-08T00:49:10.820124Z","end":"2026-04-08T00:49:10.964677Z","steps":["trace[1012841107] 'agreement among raft nodes before linearized reading' (duration: 144.330126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:10.965174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.134888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-08T00:49:10.96524Z","caller":"traceutil/trace.go:171","msg":"trace[317789426] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8515; }","duration":"151.240972ms","start":"2026-04-08T00:49:10.813975Z","end":"2026-04-08T00:49:10.965216Z","steps":["trace[317789426] 'agreement among raft nodes before linearized reading' (duration: 150.471864ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:50:07.59473Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024be.wal"} {"level":"info","ts":"2026-04-08T00:50:09.13882Z","caller":"traceutil/trace.go:171","msg":"trace[1674910073] transaction","detail":"{read_only:false; response_revision:9093; number_of_response:1; }","duration":"105.380107ms","start":"2026-04-08T00:50:09.033412Z","end":"2026-04-08T00:50:09.138792Z","steps":["trace[1674910073] 'process raft request' (duration: 105.099571ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:50:19.728071Z","caller":"traceutil/trace.go:171","msg":"trace[515410728] transaction","detail":"{read_only:false; response_revision:9156; number_of_response:1; }","duration":"285.40932ms","start":"2026-04-08T00:50:19.442627Z","end":"2026-04-08T00:50:19.728036Z","steps":["trace[515410728] 'process raft request' (duration: 228.898231ms)","trace[515410728] 'compare' (duration: 56.09766ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:50:19.728379Z","caller":"traceutil/trace.go:171","msg":"trace[1897524762] linearizableReadLoop","detail":"{readStateIndex:9474; appliedIndex:9472; }","duration":"272.082711ms","start":"2026-04-08T00:50:19.456269Z","end":"2026-04-08T00:50:19.728352Z","steps":["trace[1897524762] 'read index received' (duration: 215.356828ms)","trace[1897524762] 'applied index is now lower than readState.Index' (duration: 56.724443ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:50:19.728418Z","caller":"traceutil/trace.go:171","msg":"trace[750055604] transaction","detail":"{read_only:false; response_revision:9157; number_of_response:1; }","duration":"285.617344ms","start":"2026-04-08T00:50:19.442787Z","end":"2026-04-08T00:50:19.728404Z","steps":["trace[750055604] 'process raft request' (duration: 285.46789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:19.728561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.278426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ml7k8\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T00:50:19.72859Z","caller":"traceutil/trace.go:171","msg":"trace[373264912] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ml7k8; range_end:; response_count:1; response_revision:9157; }","duration":"272.351227ms","start":"2026-04-08T00:50:19.456232Z","end":"2026-04-08T00:50:19.728584Z","steps":["trace[373264912] 'agreement among raft nodes before linearized reading' (duration: 272.219785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:19.728702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.949228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:50:19.728823Z","caller":"traceutil/trace.go:171","msg":"trace[403824486] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:9157; }","duration":"219.111141ms","start":"2026-04-08T00:50:19.50969Z","end":"2026-04-08T00:50:19.728801Z","steps":["trace[403824486] 'agreement among raft nodes before linearized reading' (duration: 218.920707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:19.728885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.770793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12209"} {"level":"info","ts":"2026-04-08T00:50:19.728962Z","caller":"traceutil/trace.go:171","msg":"trace[1544467131] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9157; }","duration":"174.910916ms","start":"2026-04-08T00:50:19.554034Z","end":"2026-04-08T00:50:19.728945Z","steps":["trace[1544467131] 'agreement among raft nodes before linearized reading' (duration: 174.63153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:19.729139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.419115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-08T00:50:19.729269Z","caller":"traceutil/trace.go:171","msg":"trace[809327384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9157; }","duration":"152.769333ms","start":"2026-04-08T00:50:19.576481Z","end":"2026-04-08T00:50:19.729251Z","steps":["trace[809327384] 'agreement among raft nodes before linearized reading' (duration: 152.18183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:19.933582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.36037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:50:19.933675Z","caller":"traceutil/trace.go:171","msg":"trace[730258680] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:9158; }","duration":"102.508593ms","start":"2026-04-08T00:50:19.83114Z","end":"2026-04-08T00:50:19.933649Z","steps":["trace[730258680] 'range keys from in-memory index tree' (duration: 102.239568ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:50:20.070289Z","caller":"traceutil/trace.go:171","msg":"trace[1524897013] linearizableReadLoop","detail":"{readStateIndex:9476; appliedIndex:9475; }","duration":"129.588757ms","start":"2026-04-08T00:50:19.940665Z","end":"2026-04-08T00:50:20.070253Z","steps":["trace[1524897013] 'read index received' (duration: 129.209439ms)","trace[1524897013] 'applied index is now lower than readState.Index' (duration: 377.998µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:50:20.070367Z","caller":"traceutil/trace.go:171","msg":"trace[1473001171] transaction","detail":"{read_only:false; response_revision:9159; number_of_response:1; }","duration":"129.854643ms","start":"2026-04-08T00:50:19.940478Z","end":"2026-04-08T00:50:20.070333Z","steps":["trace[1473001171] 'process raft request' (duration: 129.509925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:20.070547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.897417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:50:20.070607Z","caller":"traceutil/trace.go:171","msg":"trace[1327528816] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:9159; }","duration":"127.011579ms","start":"2026-04-08T00:50:19.943576Z","end":"2026-04-08T00:50:20.070588Z","steps":["trace[1327528816] 'agreement among raft nodes before linearized reading' (duration: 126.883157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:20.070597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.901144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12209"} {"level":"info","ts":"2026-04-08T00:50:20.070672Z","caller":"traceutil/trace.go:171","msg":"trace[1069726370] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9159; }","duration":"129.998566ms","start":"2026-04-08T00:50:19.940655Z","end":"2026-04-08T00:50:20.070654Z","steps":["trace[1069726370] 'agreement among raft nodes before linearized reading' (duration: 129.72831ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:51:17.373731Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9ef3b4f68930dc2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-08T00:51:17.380866Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-08T00:51:17.381004Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-04-08T00:52:09.113336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.931003ms","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-08T00:52:09.113457Z","caller":"traceutil/trace.go:171","msg":"trace[1878584557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10037; }","duration":"114.095437ms","start":"2026-04-08T00:52:08.999325Z","end":"2026-04-08T00:52:09.11342Z","steps":["trace[1878584557] 'range keys from in-memory index tree' (duration: 113.714909ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:52:38.514632Z","caller":"traceutil/trace.go:171","msg":"trace[1807287603] transaction","detail":"{read_only:false; response_revision:10299; number_of_response:1; }","duration":"107.705364ms","start":"2026-04-08T00:52:38.406898Z","end":"2026-04-08T00:52:38.514604Z","steps":["trace[1807287603] 'process raft request' (duration: 46.853926ms)","trace[1807287603] 'compare' (duration: 60.627293ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:52:38.51482Z","caller":"traceutil/trace.go:171","msg":"trace[45535203] transaction","detail":"{read_only:false; response_revision:10300; number_of_response:1; }","duration":"107.835567ms","start":"2026-04-08T00:52:38.406949Z","end":"2026-04-08T00:52:38.514785Z","steps":["trace[45535203] 'process raft request' (duration: 107.608192ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:53:48.35765Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8305} {"level":"info","ts":"2026-04-08T00:53:48.438104Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8305,"took":"77.754176ms","hash":1362005776,"current-db-size-bytes":58040320,"current-db-size":"58 MB","current-db-size-in-use-bytes":24829952,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-08T00:53:48.438252Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1362005776,"revision":8305,"compact-revision":6128} {"level":"info","ts":"2026-04-08T00:58:48.370259Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10728} {"level":"info","ts":"2026-04-08T00:58:48.449772Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10728,"took":"76.213355ms","hash":3960182008,"current-db-size-bytes":58040320,"current-db-size":"58 MB","current-db-size-in-use-bytes":20500480,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-08T00:58:48.449836Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3960182008,"revision":10728,"compact-revision":8305}