{"level":"warn","ts":"2026-09-22T01:51:36.494566Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T01:51:36.494671Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.96: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://162.253.55.96:2380","--initial-cluster=instance=https://162.253.55.96:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.96:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.96: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-09-22T01:51:36.494741Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T01:51:36.494751Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.96:2380"]} {"level":"info","ts":"2026-09-22T01:51:36.494802Z","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-09-22T01:51:36.495481Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.96:2379"]} {"level":"info","ts":"2026-09-22T01:51:36.495573Z","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://162.253.55.96:2380"],"listen-peer-urls":["https://162.253.55.96:2380"],"advertise-client-urls":["https://162.253.55.96:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.96:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.96: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-09-22T01:51:36.501617Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.783687ms"} {"level":"info","ts":"2026-09-22T01:51:36.510454Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"64a80de7883e6886","cluster-id":"8160e60c0578d165"} {"level":"info","ts":"2026-09-22T01:51:36.510613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 switched to configuration voters=()"} {"level":"info","ts":"2026-09-22T01:51:36.510667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 became follower at term 0"} {"level":"info","ts":"2026-09-22T01:51:36.5107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 64a80de7883e6886 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-22T01:51:36.51073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 became follower at term 1"} {"level":"info","ts":"2026-09-22T01:51:36.510856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 switched to configuration voters=(7253062487954581638)"} {"level":"warn","ts":"2026-09-22T01:51:36.519044Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-22T01:51:36.525318Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-22T01:51:36.531207Z","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-09-22T01:51:36.533924Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"64a80de7883e6886","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-22T01:51:36.534136Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"64a80de7883e6886","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-22T01:51:36.534185Z","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-09-22T01:51:36.534333Z","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-09-22T01:51:36.534344Z","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-09-22T01:51:36.536253Z","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-09-22T01:51:36.536555Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"64a80de7883e6886","initial-advertise-peer-urls":["https://162.253.55.96:2380"],"listen-peer-urls":["https://162.253.55.96:2380"],"advertise-client-urls":["https://162.253.55.96:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.96:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-22T01:51:36.536585Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-22T01:51:36.537371Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.96:2380"} {"level":"info","ts":"2026-09-22T01:51:36.537493Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.96:2380"} {"level":"info","ts":"2026-09-22T01:51:36.537652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 switched to configuration voters=(7253062487954581638)"} {"level":"info","ts":"2026-09-22T01:51:36.537928Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8160e60c0578d165","local-member-id":"64a80de7883e6886","added-peer-id":"64a80de7883e6886","added-peer-peer-urls":["https://162.253.55.96:2380"]} {"level":"info","ts":"2026-09-22T01:51:37.211213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 is starting a new election at term 1"} {"level":"info","ts":"2026-09-22T01:51:37.211298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-22T01:51:37.21134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 received MsgPreVoteResp from 64a80de7883e6886 at term 1"} {"level":"info","ts":"2026-09-22T01:51:37.211365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 became candidate at term 2"} {"level":"info","ts":"2026-09-22T01:51:37.211377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 received MsgVoteResp from 64a80de7883e6886 at term 2"} {"level":"info","ts":"2026-09-22T01:51:37.211394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 became leader at term 2"} {"level":"info","ts":"2026-09-22T01:51:37.211413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 64a80de7883e6886 elected leader 64a80de7883e6886 at term 2"} {"level":"info","ts":"2026-09-22T01:51:37.212846Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"64a80de7883e6886","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.96:2379]}","request-path":"/0/members/64a80de7883e6886/attributes","cluster-id":"8160e60c0578d165","publish-timeout":"7s"} {"level":"info","ts":"2026-09-22T01:51:37.213195Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:51:37.214304Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:51:37.214896Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:51:37.21518Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-22T01:51:37.215208Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-22T01:51:37.21526Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8160e60c0578d165","local-member-id":"64a80de7883e6886","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:51:37.215392Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:51:37.215429Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:51:37.21818Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.96:2379"} {"level":"info","ts":"2026-09-22T01:51:37.220594Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-22T01:51:52.879686Z","caller":"traceutil/trace.go:171","msg":"trace[1698722217] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"190.701925ms","start":"2026-09-22T01:51:52.688957Z","end":"2026-09-22T01:51:52.879659Z","steps":["trace[1698722217] 'process raft request' (duration: 190.515711ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:58.384799Z","caller":"traceutil/trace.go:171","msg":"trace[524005520] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"166.158424ms","start":"2026-09-22T01:51:58.218584Z","end":"2026-09-22T01:51:58.384743Z","steps":["trace[524005520] 'process raft request' (duration: 165.97661ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:03.648924Z","caller":"traceutil/trace.go:171","msg":"trace[9252451] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"149.137669ms","start":"2026-09-22T01:52:03.499762Z","end":"2026-09-22T01:52:03.6489Z","steps":["trace[9252451] 'process raft request' (duration: 148.981836ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:31.38556Z","caller":"traceutil/trace.go:171","msg":"trace[646904319] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"100.539126ms","start":"2026-09-22T01:52:31.284942Z","end":"2026-09-22T01:52:31.385481Z","steps":["trace[646904319] 'process raft request' (duration: 100.293001ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:32.00231Z","caller":"traceutil/trace.go:171","msg":"trace[332978240] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:740; }","duration":"181.879301ms","start":"2026-09-22T01:52:31.820395Z","end":"2026-09-22T01:52:32.002274Z","steps":["trace[332978240] 'read index received' (duration: 181.614016ms)","trace[332978240] 'applied index is now lower than readState.Index' (duration: 263.835µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:52:32.002426Z","caller":"traceutil/trace.go:171","msg":"trace[907013922] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"390.763997ms","start":"2026-09-22T01:52:31.611601Z","end":"2026-09-22T01:52:32.002365Z","steps":["trace[907013922] 'process raft request' (duration: 390.44135ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:32.002647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.174377ms","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-09-22T01:52:32.002727Z","caller":"traceutil/trace.go:171","msg":"trace[963692831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:717; }","duration":"182.405273ms","start":"2026-09-22T01:52:31.820306Z","end":"2026-09-22T01:52:32.002711Z","steps":["trace[963692831] 'agreement among raft nodes before linearized reading' (duration: 182.095866ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:32.0035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:52:31.611553Z","time spent":"391.120985ms","remote":"127.0.0.1:37216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T01:52:48.862613Z","caller":"traceutil/trace.go:171","msg":"trace[1016871738] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"137.573633ms","start":"2026-09-22T01:52:48.725008Z","end":"2026-09-22T01:52:48.862582Z","steps":["trace[1016871738] 'process raft request' (duration: 132.022581ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:53:19.211162Z","caller":"traceutil/trace.go:171","msg":"trace[1533988869] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"372.211352ms","start":"2026-09-22T01:53:18.838916Z","end":"2026-09-22T01:53:19.211127Z","steps":["trace[1533988869] 'process raft request' (duration: 371.945196ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:53:19.211353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:53:18.838878Z","time spent":"372.376415ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T01:54:10.729829Z","caller":"traceutil/trace.go:171","msg":"trace[632232217] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"115.547499ms","start":"2026-09-22T01:54:10.614243Z","end":"2026-09-22T01:54:10.72979Z","steps":["trace[632232217] 'process raft request' (duration: 80.696286ms)","trace[632232217] 'compare' (duration: 34.536656ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:54:11.009798Z","caller":"traceutil/trace.go:171","msg":"trace[288295373] linearizableReadLoop","detail":"{readStateIndex:1998; appliedIndex:1996; }","duration":"274.016893ms","start":"2026-09-22T01:54:10.735736Z","end":"2026-09-22T01:54:11.009753Z","steps":["trace[288295373] 'read index received' (duration: 63.971229ms)","trace[288295373] 'applied index is now lower than readState.Index' (duration: 210.044624ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:54:11.009944Z","caller":"traceutil/trace.go:171","msg":"trace[1599168615] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"274.930124ms","start":"2026-09-22T01:54:10.735003Z","end":"2026-09-22T01:54:11.009933Z","steps":["trace[1599168615] 'process raft request' (duration: 273.056823ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:54:11.010136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.377302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-22T01:54:11.010163Z","caller":"traceutil/trace.go:171","msg":"trace[2116480361] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1925; }","duration":"274.450583ms","start":"2026-09-22T01:54:10.735703Z","end":"2026-09-22T01:54:11.010153Z","steps":["trace[2116480361] 'agreement among raft nodes before linearized reading' (duration: 274.361341ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:54:11.074592Z","caller":"traceutil/trace.go:171","msg":"trace[353081199] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"211.927467ms","start":"2026-09-22T01:54:10.862627Z","end":"2026-09-22T01:54:11.074555Z","steps":["trace[353081199] 'process raft request' (duration: 211.65512ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:54:11.074722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.123327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T01:54:11.074875Z","caller":"traceutil/trace.go:171","msg":"trace[462100252] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1926; }","duration":"279.334092ms","start":"2026-09-22T01:54:10.795517Z","end":"2026-09-22T01:54:11.074851Z","steps":["trace[462100252] 'agreement among raft nodes before linearized reading' (duration: 279.059816ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:54:11.074899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.242623ms","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-09-22T01:54:11.074997Z","caller":"traceutil/trace.go:171","msg":"trace[2063979128] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1926; }","duration":"300.482418ms","start":"2026-09-22T01:54:10.774492Z","end":"2026-09-22T01:54:11.074974Z","steps":["trace[2063979128] 'agreement among raft nodes before linearized reading' (duration: 300.088009ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:54:11.075055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:54:10.774481Z","time spent":"300.56336ms","remote":"127.0.0.1:50476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-22T01:54:56.589406Z","caller":"traceutil/trace.go:171","msg":"trace[1541127517] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"195.896686ms","start":"2026-09-22T01:54:56.393415Z","end":"2026-09-22T01:54:56.589312Z","steps":["trace[1541127517] 'process raft request' (duration: 140.96331ms)","trace[1541127517] 'compare' (duration: 54.626058ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:54:56.589386Z","caller":"traceutil/trace.go:171","msg":"trace[1907611515] linearizableReadLoop","detail":"{readStateIndex:2305; appliedIndex:2304; }","duration":"175.788464ms","start":"2026-09-22T01:54:56.413541Z","end":"2026-09-22T01:54:56.589329Z","steps":["trace[1907611515] 'read index received' (duration: 120.874448ms)","trace[1907611515] 'applied index is now lower than readState.Index' (duration: 54.911806ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:54:56.58984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.229454ms","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-09-22T01:54:56.589931Z","caller":"traceutil/trace.go:171","msg":"trace[692695426] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2223; }","duration":"176.403538ms","start":"2026-09-22T01:54:56.413512Z","end":"2026-09-22T01:54:56.589916Z","steps":["trace[692695426] 'agreement among raft nodes before linearized reading' (duration: 175.952008ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:55:51.774501Z","caller":"traceutil/trace.go:171","msg":"trace[1882260466] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"188.553101ms","start":"2026-09-22T01:55:51.58592Z","end":"2026-09-22T01:55:51.774473Z","steps":["trace[1882260466] 'process raft request' (duration: 188.163942ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:56:25.247891Z","caller":"traceutil/trace.go:171","msg":"trace[1409918226] transaction","detail":"{read_only:false; response_revision:2895; number_of_response:1; }","duration":"164.690785ms","start":"2026-09-22T01:56:25.08316Z","end":"2026-09-22T01:56:25.247851Z","steps":["trace[1409918226] 'process raft request' (duration: 105.179637ms)","trace[1409918226] 'compare' (duration: 59.303043ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:56:25.740127Z","caller":"traceutil/trace.go:171","msg":"trace[1654607485] transaction","detail":"{read_only:false; response_revision:2896; number_of_response:1; }","duration":"184.105932ms","start":"2026-09-22T01:56:25.555988Z","end":"2026-09-22T01:56:25.740094Z","steps":["trace[1654607485] 'process raft request' (duration: 183.929467ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:56:25.741238Z","caller":"traceutil/trace.go:171","msg":"trace[1066939643] transaction","detail":"{read_only:false; response_revision:2897; number_of_response:1; }","duration":"131.316434ms","start":"2026-09-22T01:56:25.609863Z","end":"2026-09-22T01:56:25.74118Z","steps":["trace[1066939643] 'process raft request' (duration: 131.1447ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:56:36.438089Z","caller":"traceutil/trace.go:171","msg":"trace[1879786117] transaction","detail":"{read_only:false; response_revision:2955; number_of_response:1; }","duration":"102.909294ms","start":"2026-09-22T01:56:36.335135Z","end":"2026-09-22T01:56:36.438044Z","steps":["trace[1879786117] 'process raft request' (duration: 30.124347ms)","trace[1879786117] 'compare' (duration: 72.233945ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:01:17.698522Z","caller":"traceutil/trace.go:171","msg":"trace[628610906] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"111.423638ms","start":"2026-09-22T02:01:17.587061Z","end":"2026-09-22T02:01:17.698485Z","steps":["trace[628610906] 'process raft request' (duration: 111.159282ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:17.699316Z","caller":"traceutil/trace.go:171","msg":"trace[172210463] transaction","detail":"{read_only:false; response_revision:5108; number_of_response:1; }","duration":"112.055393ms","start":"2026-09-22T02:01:17.587222Z","end":"2026-09-22T02:01:17.699278Z","steps":["trace[172210463] 'process raft request' (duration: 111.185243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:27.732455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.405468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T02:01:27.732637Z","caller":"traceutil/trace.go:171","msg":"trace[1149399551] linearizableReadLoop","detail":"{readStateIndex:5600; appliedIndex:5599; }","duration":"236.97946ms","start":"2026-09-22T02:01:27.495635Z","end":"2026-09-22T02:01:27.732614Z","steps":["trace[1149399551] 'read index received' (duration: 39.811µs)","trace[1149399551] 'applied index is now lower than readState.Index' (duration: 236.921739ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:01:27.732736Z","caller":"traceutil/trace.go:171","msg":"trace[1470893286] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"261.076236ms","start":"2026-09-22T02:01:27.471591Z","end":"2026-09-22T02:01:27.732667Z","steps":["trace[1470893286] 'process raft request' (duration: 10.113585ms)","trace[1470893286] 'compare' (duration: 250.215974ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:01:27.733043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.353909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-99e398c5-1b68-4899-afb3-b3890c48ce6a\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-09-22T02:01:27.733255Z","caller":"traceutil/trace.go:171","msg":"trace[1476756814] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-99e398c5-1b68-4899-afb3-b3890c48ce6a; range_end:; response_count:1; response_revision:5397; }","duration":"237.637155ms","start":"2026-09-22T02:01:27.495595Z","end":"2026-09-22T02:01:27.733232Z","steps":["trace[1476756814] 'agreement among raft nodes before linearized reading' (duration: 237.164515ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:27.753048Z","caller":"traceutil/trace.go:171","msg":"trace[1849651442] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"187.605787ms","start":"2026-09-22T02:01:27.565403Z","end":"2026-09-22T02:01:27.753009Z","steps":["trace[1849651442] 'process raft request' (duration: 187.349001ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:27.753106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.087693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T02:01:27.753229Z","caller":"traceutil/trace.go:171","msg":"trace[865090084] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:5398; }","duration":"213.243446ms","start":"2026-09-22T02:01:27.539936Z","end":"2026-09-22T02:01:27.75318Z","steps":["trace[865090084] 'agreement among raft nodes before linearized reading' (duration: 213.033991ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:28.078038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.152844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:01:28.078159Z","caller":"traceutil/trace.go:171","msg":"trace[1844927785] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5401; }","duration":"125.329068ms","start":"2026-09-22T02:01:27.952803Z","end":"2026-09-22T02:01:28.078132Z","steps":["trace[1844927785] 'range keys from in-memory index tree' (duration: 125.073351ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:37.262592Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2961} {"level":"info","ts":"2026-09-22T02:01:37.353321Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2961,"took":"88.662265ms","hash":146689945,"current-db-size-bytes":54423552,"current-db-size":"54 MB","current-db-size-in-use-bytes":30994432,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-22T02:01:37.353417Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":146689945,"revision":2961,"compact-revision":-1} {"level":"info","ts":"2026-09-22T02:01:42.982129Z","caller":"traceutil/trace.go:171","msg":"trace[448455] linearizableReadLoop","detail":"{readStateIndex:5891; appliedIndex:5890; }","duration":"124.444159ms","start":"2026-09-22T02:01:42.857659Z","end":"2026-09-22T02:01:42.982103Z","steps":["trace[448455] 'read index received' (duration: 124.171613ms)","trace[448455] 'applied index is now lower than readState.Index' (duration: 271.046µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:01:42.9822Z","caller":"traceutil/trace.go:171","msg":"trace[1124073518] transaction","detail":"{read_only:false; response_revision:5678; number_of_response:1; }","duration":"136.703879ms","start":"2026-09-22T02:01:42.845456Z","end":"2026-09-22T02:01:42.98216Z","steps":["trace[1124073518] 'process raft request' (duration: 136.467914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:42.982419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.48761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-22T02:01:42.982469Z","caller":"traceutil/trace.go:171","msg":"trace[301068808] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5678; }","duration":"106.570451ms","start":"2026-09-22T02:01:42.875886Z","end":"2026-09-22T02:01:42.982457Z","steps":["trace[301068808] 'agreement among raft nodes before linearized reading' (duration: 106.403868ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:42.982658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.927689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-xqntm\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-09-22T02:01:42.9828Z","caller":"traceutil/trace.go:171","msg":"trace[415315619] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-xqntm; range_end:; response_count:1; response_revision:5678; }","duration":"125.163014ms","start":"2026-09-22T02:01:42.857584Z","end":"2026-09-22T02:01:42.982747Z","steps":["trace[415315619] 'agreement among raft nodes before linearized reading' (duration: 124.713684ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:43.133899Z","caller":"traceutil/trace.go:171","msg":"trace[472280448] transaction","detail":"{read_only:false; response_revision:5679; number_of_response:1; }","duration":"144.186245ms","start":"2026-09-22T02:01:42.989669Z","end":"2026-09-22T02:01:43.133855Z","steps":["trace[472280448] 'process raft request' (duration: 81.551977ms)","trace[472280448] 'compare' (duration: 61.963353ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:01:43.140867Z","caller":"traceutil/trace.go:171","msg":"trace[2019850684] transaction","detail":"{read_only:false; response_revision:5680; number_of_response:1; }","duration":"146.609099ms","start":"2026-09-22T02:01:42.994226Z","end":"2026-09-22T02:01:43.140835Z","steps":["trace[2019850684] 'process raft request' (duration: 146.380364ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:43.184932Z","caller":"traceutil/trace.go:171","msg":"trace[569211469] transaction","detail":"{read_only:false; response_revision:5681; number_of_response:1; }","duration":"100.312243ms","start":"2026-09-22T02:01:43.084559Z","end":"2026-09-22T02:01:43.184871Z","steps":["trace[569211469] 'process raft request' (duration: 100.009437ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:54.649321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.299774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z72w7\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-09-22T02:01:54.64948Z","caller":"traceutil/trace.go:171","msg":"trace[2064669461] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z72w7; range_end:; response_count:1; response_revision:5923; }","duration":"105.541019ms","start":"2026-09-22T02:01:54.54391Z","end":"2026-09-22T02:01:54.649451Z","steps":["trace[2064669461] 'range keys from in-memory index tree' (duration: 104.976647ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:54.649425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.465128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4d58fd97-a787-461b-9d7c-14dac59a1551\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-22T02:01:54.649553Z","caller":"traceutil/trace.go:171","msg":"trace[1939705664] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4d58fd97-a787-461b-9d7c-14dac59a1551; range_end:; response_count:1; response_revision:5923; }","duration":"119.661582ms","start":"2026-09-22T02:01:54.529862Z","end":"2026-09-22T02:01:54.649524Z","steps":["trace[1939705664] 'range keys from in-memory index tree' (duration: 119.185952ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:05.329394Z","caller":"traceutil/trace.go:171","msg":"trace[2088504880] transaction","detail":"{read_only:false; response_revision:6008; number_of_response:1; }","duration":"100.35306ms","start":"2026-09-22T02:02:05.22899Z","end":"2026-09-22T02:02:05.329343Z","steps":["trace[2088504880] 'process raft request' (duration: 98.739544ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:05.329699Z","caller":"traceutil/trace.go:171","msg":"trace[694342671] transaction","detail":"{read_only:false; response_revision:6010; number_of_response:1; }","duration":"100.097194ms","start":"2026-09-22T02:02:05.229558Z","end":"2026-09-22T02:02:05.329655Z","steps":["trace[694342671] 'process raft request' (duration: 99.789367ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:02:05.329762Z","caller":"traceutil/trace.go:171","msg":"trace[249198307] transaction","detail":"{read_only:false; response_revision:6009; number_of_response:1; }","duration":"100.634217ms","start":"2026-09-22T02:02:05.229095Z","end":"2026-09-22T02:02:05.329729Z","steps":["trace[249198307] 'process raft request' (duration: 100.159556ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:06:37.274613Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5602} {"level":"info","ts":"2026-09-22T02:06:37.368189Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5602,"took":"91.457965ms","hash":581150645,"current-db-size-bytes":54423552,"current-db-size":"54 MB","current-db-size-in-use-bytes":28446720,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-22T02:06:37.368272Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":581150645,"revision":5602,"compact-revision":2961} {"level":"info","ts":"2026-09-22T02:08:11.011002Z","caller":"traceutil/trace.go:171","msg":"trace[794896050] transaction","detail":"{read_only:false; response_revision:8737; number_of_response:1; }","duration":"109.983801ms","start":"2026-09-22T02:08:10.900984Z","end":"2026-09-22T02:08:11.010968Z","steps":["trace[794896050] 'process raft request' (duration: 56.507245ms)","trace[794896050] 'compare' (duration: 53.291122ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:08:22.74827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.539584ms","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-09-22T02:08:22.748398Z","caller":"traceutil/trace.go:171","msg":"trace[779807351] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8800; }","duration":"195.733198ms","start":"2026-09-22T02:08:22.552638Z","end":"2026-09-22T02:08:22.748372Z","steps":["trace[779807351] 'range keys from in-memory index tree' (duration: 195.251947ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:08:23.185666Z","caller":"traceutil/trace.go:171","msg":"trace[916866156] transaction","detail":"{read_only:false; response_revision:8802; number_of_response:1; }","duration":"106.920853ms","start":"2026-09-22T02:08:23.078712Z","end":"2026-09-22T02:08:23.185633Z","steps":["trace[916866156] 'process raft request' (duration: 106.679547ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:08:23.406327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.740658ms","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-09-22T02:08:23.406434Z","caller":"traceutil/trace.go:171","msg":"trace[2027201344] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8802; }","duration":"106.897741ms","start":"2026-09-22T02:08:23.299509Z","end":"2026-09-22T02:08:23.406406Z","steps":["trace[2027201344] 'range keys from in-memory index tree' (duration: 106.518703ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:08:53.45449Z","caller":"traceutil/trace.go:171","msg":"trace[1721189557] linearizableReadLoop","detail":"{readStateIndex:9337; appliedIndex:9336; }","duration":"146.295143ms","start":"2026-09-22T02:08:53.308173Z","end":"2026-09-22T02:08:53.454468Z","steps":["trace[1721189557] 'read index received' (duration: 146.100258ms)","trace[1721189557] 'applied index is now lower than readState.Index' (duration: 193.635µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:08:53.454787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.592389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:08:53.455085Z","caller":"traceutil/trace.go:171","msg":"trace[2138437887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9018; }","duration":"146.934667ms","start":"2026-09-22T02:08:53.308127Z","end":"2026-09-22T02:08:53.455062Z","steps":["trace[2138437887] 'agreement among raft nodes before linearized reading' (duration: 146.575209ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:08:53.454862Z","caller":"traceutil/trace.go:171","msg":"trace[1713249793] transaction","detail":"{read_only:false; response_revision:9018; number_of_response:1; }","duration":"161.554184ms","start":"2026-09-22T02:08:53.293288Z","end":"2026-09-22T02:08:53.454843Z","steps":["trace[1713249793] 'process raft request' (duration: 161.042263ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:09:00.837945Z","caller":"traceutil/trace.go:171","msg":"trace[16805099] transaction","detail":"{read_only:false; response_revision:9060; number_of_response:1; }","duration":"124.390522ms","start":"2026-09-22T02:09:00.713521Z","end":"2026-09-22T02:09:00.837911Z","steps":["trace[16805099] 'process raft request' (duration: 124.203408ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:09:07.647709Z","caller":"traceutil/trace.go:171","msg":"trace[980151508] transaction","detail":"{read_only:false; response_revision:9134; number_of_response:1; }","duration":"143.884478ms","start":"2026-09-22T02:09:07.503794Z","end":"2026-09-22T02:09:07.647678Z","steps":["trace[980151508] 'process raft request' (duration: 143.699204ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:09:21.935981Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002579.wal"} {"level":"info","ts":"2026-09-22T02:10:03.233402Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"64a80de7883e6886","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T02:10:03.242832Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-22T02:10:03.24326Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-22T02:10:30.107053Z","caller":"traceutil/trace.go:171","msg":"trace[1635851057] transaction","detail":"{read_only:false; response_revision:9914; number_of_response:1; }","duration":"119.021772ms","start":"2026-09-22T02:10:29.988006Z","end":"2026-09-22T02:10:30.107027Z","steps":["trace[1635851057] 'process raft request' (duration: 118.870038ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:11:37.282309Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7968} {"level":"info","ts":"2026-09-22T02:11:37.363709Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7968,"took":"79.391705ms","hash":3463571903,"current-db-size-bytes":54423552,"current-db-size":"54 MB","current-db-size-in-use-bytes":24989696,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-22T02:11:37.363769Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3463571903,"revision":7968,"compact-revision":5602} {"level":"info","ts":"2026-09-22T02:16:37.291559Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10382} {"level":"info","ts":"2026-09-22T02:16:37.380841Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10382,"took":"86.623866ms","hash":2119484625,"current-db-size-bytes":54423552,"current-db-size":"54 MB","current-db-size-in-use-bytes":28024832,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-22T02:16:37.380915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2119484625,"revision":10382,"compact-revision":7968} {"level":"info","ts":"2026-09-22T02:20:43.984302Z","caller":"traceutil/trace.go:171","msg":"trace[1930490887] linearizableReadLoop","detail":"{readStateIndex:14874; appliedIndex:14873; }","duration":"157.120416ms","start":"2026-09-22T02:20:43.827125Z","end":"2026-09-22T02:20:43.984245Z","steps":["trace[1930490887] 'read index received' (duration: 156.926281ms)","trace[1930490887] 'applied index is now lower than readState.Index' (duration: 190.665µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:20:43.984327Z","caller":"traceutil/trace.go:171","msg":"trace[1143003329] transaction","detail":"{read_only:false; response_revision:14379; number_of_response:1; }","duration":"313.125848ms","start":"2026-09-22T02:20:43.671167Z","end":"2026-09-22T02:20:43.984293Z","steps":["trace[1143003329] 'process raft request' (duration: 312.910693ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:20:43.984498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:20:43.671137Z","time spent":"313.270711ms","remote":"127.0.0.1:37216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12479,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:20:43.984692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.559686ms","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-09-22T02:20:43.984849Z","caller":"traceutil/trace.go:171","msg":"trace[791659533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14379; }","duration":"157.697759ms","start":"2026-09-22T02:20:43.827054Z","end":"2026-09-22T02:20:43.984752Z","steps":["trace[791659533] 'agreement among raft nodes before linearized reading' (duration: 157.341001ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:21:07.638544Z","caller":"traceutil/trace.go:171","msg":"trace[1518469791] transaction","detail":"{read_only:false; response_revision:14698; number_of_response:1; }","duration":"144.423114ms","start":"2026-09-22T02:21:07.49409Z","end":"2026-09-22T02:21:07.638513Z","steps":["trace[1518469791] 'process raft request' (duration: 144.218409ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:21:37.300238Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12742} {"level":"info","ts":"2026-09-22T02:21:37.389976Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12742,"took":"87.334023ms","hash":1992395449,"current-db-size-bytes":54423552,"current-db-size":"54 MB","current-db-size-in-use-bytes":29057024,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-22T02:21:37.390045Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1992395449,"revision":12742,"compact-revision":10382} {"level":"warn","ts":"2026-09-22T02:22:33.973565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.108248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-22T02:22:33.973681Z","caller":"traceutil/trace.go:171","msg":"trace[19648527] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15514; }","duration":"109.292372ms","start":"2026-09-22T02:22:33.864364Z","end":"2026-09-22T02:22:33.973656Z","steps":["trace[19648527] 'range keys from in-memory index tree' (duration: 108.932184ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:22:33.973557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.841814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-22T02:22:33.973785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.149181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:22:33.973854Z","caller":"traceutil/trace.go:171","msg":"trace[491206134] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15514; }","duration":"101.197302ms","start":"2026-09-22T02:22:33.872646Z","end":"2026-09-22T02:22:33.973843Z","steps":["trace[491206134] 'range keys from in-memory index tree' (duration: 100.788912ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:22:33.973883Z","caller":"traceutil/trace.go:171","msg":"trace[1462713834] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15514; }","duration":"110.366116ms","start":"2026-09-22T02:22:33.863488Z","end":"2026-09-22T02:22:33.973854Z","steps":["trace[1462713834] 'range keys from in-memory index tree' (duration: 109.928107ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:22:34.84169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.769768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-bgq7w\" ","response":"range_response_count:1 size:20656"} {"level":"info","ts":"2026-09-22T02:22:34.84181Z","caller":"traceutil/trace.go:171","msg":"trace[421110504] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-bgq7w; range_end:; response_count:1; response_revision:15516; }","duration":"125.906331ms","start":"2026-09-22T02:22:34.715877Z","end":"2026-09-22T02:22:34.841783Z","steps":["trace[421110504] 'range keys from in-memory index tree' (duration: 125.555604ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:26:37.308048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14898} {"level":"info","ts":"2026-09-22T02:26:37.407991Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14898,"took":"96.668218ms","hash":3561634940,"current-db-size-bytes":54423552,"current-db-size":"54 MB","current-db-size-in-use-bytes":31281152,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-22T02:26:37.408086Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3561634940,"revision":14898,"compact-revision":12742} {"level":"info","ts":"2026-09-22T02:28:10.337591Z","caller":"traceutil/trace.go:171","msg":"trace[25257605] transaction","detail":"{read_only:false; response_revision:18036; number_of_response:1; }","duration":"143.365579ms","start":"2026-09-22T02:28:10.194201Z","end":"2026-09-22T02:28:10.337566Z","steps":["trace[25257605] 'process raft request' (duration: 143.215636ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:28:10.365791Z","caller":"traceutil/trace.go:171","msg":"trace[2018659611] transaction","detail":"{read_only:false; response_revision:18037; number_of_response:1; }","duration":"170.103057ms","start":"2026-09-22T02:28:10.195664Z","end":"2026-09-22T02:28:10.365767Z","steps":["trace[2018659611] 'process raft request' (duration: 165.470693ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:28:16.89363Z","caller":"traceutil/trace.go:171","msg":"trace[1231166772] transaction","detail":"{read_only:false; response_revision:18076; number_of_response:1; }","duration":"179.031136ms","start":"2026-09-22T02:28:16.714569Z","end":"2026-09-22T02:28:16.8936Z","steps":["trace[1231166772] 'process raft request' (duration: 178.819321ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:28:16.963494Z","caller":"traceutil/trace.go:171","msg":"trace[675153789] linearizableReadLoop","detail":"{readStateIndex:18694; appliedIndex:18692; }","duration":"194.442579ms","start":"2026-09-22T02:28:16.769019Z","end":"2026-09-22T02:28:16.963461Z","steps":["trace[675153789] 'read index received' (duration: 124.405436ms)","trace[675153789] 'applied index is now lower than readState.Index' (duration: 70.035723ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:28:16.963831Z","caller":"traceutil/trace.go:171","msg":"trace[778295317] transaction","detail":"{read_only:false; response_revision:18077; number_of_response:1; }","duration":"239.353091ms","start":"2026-09-22T02:28:16.72443Z","end":"2026-09-22T02:28:16.963783Z","steps":["trace[778295317] 'process raft request' (duration: 231.782913ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:28:16.966803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.769793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-22T02:28:16.966887Z","caller":"traceutil/trace.go:171","msg":"trace[1675100374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18077; }","duration":"197.926877ms","start":"2026-09-22T02:28:16.768942Z","end":"2026-09-22T02:28:16.966869Z","steps":["trace[1675100374] 'agreement among raft nodes before linearized reading' (duration: 194.735466ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:28:17.320639Z","caller":"traceutil/trace.go:171","msg":"trace[1586083271] linearizableReadLoop","detail":"{readStateIndex:18696; appliedIndex:18694; }","duration":"200.208609ms","start":"2026-09-22T02:28:17.120406Z","end":"2026-09-22T02:28:17.320615Z","steps":["trace[1586083271] 'read index received' (duration: 119.228491ms)","trace[1586083271] 'applied index is now lower than readState.Index' (duration: 80.979048ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:28:17.320801Z","caller":"traceutil/trace.go:171","msg":"trace[2031891827] transaction","detail":"{read_only:false; response_revision:18079; number_of_response:1; }","duration":"349.661744ms","start":"2026-09-22T02:28:16.971086Z","end":"2026-09-22T02:28:17.320748Z","steps":["trace[2031891827] 'process raft request' (duration: 349.435909ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:28:17.320887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.461644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pf2hv\" ","response":"range_response_count:1 size:22129"} {"level":"info","ts":"2026-09-22T02:28:17.320924Z","caller":"traceutil/trace.go:171","msg":"trace[1579957103] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pf2hv; range_end:; response_count:1; response_revision:18079; }","duration":"200.564177ms","start":"2026-09-22T02:28:17.120349Z","end":"2026-09-22T02:28:17.320913Z","steps":["trace[1579957103] 'agreement among raft nodes before linearized reading' (duration: 200.400733ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:28:17.321006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:28:16.971061Z","time spent":"349.838588ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:28:17.321031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.397387ms","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-09-22T02:28:17.321089Z","caller":"traceutil/trace.go:171","msg":"trace[1662883630] transaction","detail":"{read_only:false; response_revision:18078; number_of_response:1; }","duration":"350.315228ms","start":"2026-09-22T02:28:16.970726Z","end":"2026-09-22T02:28:17.321041Z","steps":["trace[1662883630] 'process raft request' (duration: 268.904031ms)","trace[1662883630] 'compare' (duration: 80.576898ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:28:17.321196Z","caller":"traceutil/trace.go:171","msg":"trace[1236943015] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18079; }","duration":"159.590432ms","start":"2026-09-22T02:28:17.161575Z","end":"2026-09-22T02:28:17.321165Z","steps":["trace[1236943015] 'agreement among raft nodes before linearized reading' (duration: 159.236824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:28:17.321329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:28:16.970707Z","time spent":"350.492723ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:30:38.437245Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"64a80de7883e6886","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T02:30:38.45151Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-22T02:30:38.451614Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-22T02:31:37.314877Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17387} {"level":"info","ts":"2026-09-22T02:31:37.42888Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17387,"took":"101.924176ms","hash":650270367,"current-db-size-bytes":54423552,"current-db-size":"54 MB","current-db-size-in-use-bytes":35340288,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-22T02:31:37.428956Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":650270367,"revision":17387,"compact-revision":14898} {"level":"info","ts":"2026-09-22T02:33:52.406884Z","caller":"traceutil/trace.go:171","msg":"trace[1318522332] transaction","detail":"{read_only:false; response_revision:20700; number_of_response:1; }","duration":"151.420527ms","start":"2026-09-22T02:33:52.255434Z","end":"2026-09-22T02:33:52.406855Z","steps":["trace[1318522332] 'process raft request' (duration: 151.253583ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:33:52.71909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.083545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:33:52.719182Z","caller":"traceutil/trace.go:171","msg":"trace[754800953] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:20700; }","duration":"236.245688ms","start":"2026-09-22T02:33:52.482915Z","end":"2026-09-22T02:33:52.71916Z","steps":["trace[754800953] 'count revisions from in-memory index tree' (duration: 235.981823ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:33:52.923093Z","caller":"traceutil/trace.go:171","msg":"trace[244887355] transaction","detail":"{read_only:false; response_revision:20701; number_of_response:1; }","duration":"130.150963ms","start":"2026-09-22T02:33:52.792846Z","end":"2026-09-22T02:33:52.922997Z","steps":["trace[244887355] 'process raft request' (duration: 129.9897ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:33:53.386336Z","caller":"traceutil/trace.go:171","msg":"trace[1622044572] transaction","detail":"{read_only:false; response_revision:20703; number_of_response:1; }","duration":"209.068853ms","start":"2026-09-22T02:33:53.177242Z","end":"2026-09-22T02:33:53.386311Z","steps":["trace[1622044572] 'process raft request' (duration: 145.389603ms)","trace[1622044572] 'compare' (duration: 63.511276ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:33:53.633737Z","caller":"traceutil/trace.go:171","msg":"trace[1394925383] transaction","detail":"{read_only:false; response_revision:20704; number_of_response:1; }","duration":"242.154972ms","start":"2026-09-22T02:33:53.391546Z","end":"2026-09-22T02:33:53.633701Z","steps":["trace[1394925383] 'process raft request' (duration: 156.727585ms)","trace[1394925383] 'compare' (duration: 85.288304ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:33:54.094193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.621875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:33:54.094448Z","caller":"traceutil/trace.go:171","msg":"trace[259167154] transaction","detail":"{read_only:false; response_revision:20708; number_of_response:1; }","duration":"200.375999ms","start":"2026-09-22T02:33:53.894054Z","end":"2026-09-22T02:33:54.09443Z","steps":["trace[259167154] 'process raft request' (duration: 200.254796ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:33:54.094457Z","caller":"traceutil/trace.go:171","msg":"trace[594002476] transaction","detail":"{read_only:false; response_revision:20707; number_of_response:1; }","duration":"258.592008ms","start":"2026-09-22T02:33:53.835816Z","end":"2026-09-22T02:33:54.094408Z","steps":["trace[594002476] 'process raft request' (duration: 100.664676ms)","trace[594002476] 'compare' (duration: 157.409631ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:33:54.094561Z","caller":"traceutil/trace.go:171","msg":"trace[1325826987] linearizableReadLoop","detail":"{readStateIndex:21409; appliedIndex:21408; }","duration":"223.086907ms","start":"2026-09-22T02:33:53.87144Z","end":"2026-09-22T02:33:54.094527Z","steps":["trace[1325826987] 'read index received' (duration: 65.017491ms)","trace[1325826987] 'applied index is now lower than readState.Index' (duration: 158.066886ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:33:54.094732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.26564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:33:54.094774Z","caller":"traceutil/trace.go:171","msg":"trace[1530452249] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:20708; }","duration":"223.365563ms","start":"2026-09-22T02:33:53.871395Z","end":"2026-09-22T02:33:54.094761Z","steps":["trace[1530452249] 'agreement among raft nodes before linearized reading' (duration: 223.24285ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:36:37.321767Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19777} {"level":"info","ts":"2026-09-22T02:36:37.437997Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19777,"took":"111.405315ms","hash":3455291924,"current-db-size-bytes":54423552,"current-db-size":"54 MB","current-db-size-in-use-bytes":30167040,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-22T02:36:37.438146Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3455291924,"revision":19777,"compact-revision":17387} {"level":"info","ts":"2026-09-22T02:40:44.066025Z","caller":"traceutil/trace.go:171","msg":"trace[543356333] transaction","detail":"{read_only:false; response_revision:24912; number_of_response:1; }","duration":"131.218529ms","start":"2026-09-22T02:40:43.934777Z","end":"2026-09-22T02:40:44.065996Z","steps":["trace[543356333] 'process raft request' (duration: 107.006558ms)","trace[543356333] 'compare' (duration: 24.082658ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:14.11536Z","caller":"traceutil/trace.go:171","msg":"trace[1606776365] linearizableReadLoop","detail":"{readStateIndex:26079; appliedIndex:26078; }","duration":"227.837974ms","start":"2026-09-22T02:41:13.887499Z","end":"2026-09-22T02:41:14.115337Z","steps":["trace[1606776365] 'read index received' (duration: 227.67149ms)","trace[1606776365] 'applied index is now lower than readState.Index' (duration: 165.234µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:14.11544Z","caller":"traceutil/trace.go:171","msg":"trace[1277551192] transaction","detail":"{read_only:false; response_revision:25250; number_of_response:1; }","duration":"249.805834ms","start":"2026-09-22T02:41:13.865608Z","end":"2026-09-22T02:41:14.115413Z","steps":["trace[1277551192] 'process raft request' (duration: 249.581849ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:14.115578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.05902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-09-22T02:41:14.115616Z","caller":"traceutil/trace.go:171","msg":"trace[744078506] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25250; }","duration":"228.136891ms","start":"2026-09-22T02:41:13.887467Z","end":"2026-09-22T02:41:14.115604Z","steps":["trace[744078506] 'agreement among raft nodes before linearized reading' (duration: 227.967596ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:14.191492Z","caller":"traceutil/trace.go:171","msg":"trace[1845384563] transaction","detail":"{read_only:false; response_revision:25251; number_of_response:1; }","duration":"283.189419ms","start":"2026-09-22T02:41:13.908286Z","end":"2026-09-22T02:41:14.191475Z","steps":["trace[1845384563] 'process raft request' (duration: 283.050096ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:14.191631Z","caller":"traceutil/trace.go:171","msg":"trace[1833433752] transaction","detail":"{read_only:false; response_revision:25252; number_of_response:1; }","duration":"259.149114ms","start":"2026-09-22T02:41:13.932455Z","end":"2026-09-22T02:41:14.191604Z","steps":["trace[1833433752] 'process raft request' (duration: 258.99145ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:14.37946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.651129ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:14.379542Z","caller":"traceutil/trace.go:171","msg":"trace[1682993946] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25252; }","duration":"123.744361ms","start":"2026-09-22T02:41:14.255786Z","end":"2026-09-22T02:41:14.37953Z","steps":["trace[1682993946] 'range keys from in-memory index tree' (duration: 123.637459ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:14.382243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.917836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:41:14.382355Z","caller":"traceutil/trace.go:171","msg":"trace[1115094780] linearizableReadLoop","detail":"{readStateIndex:26082; appliedIndex:26080; }","duration":"207.877369ms","start":"2026-09-22T02:41:14.174462Z","end":"2026-09-22T02:41:14.382339Z","steps":["trace[1115094780] 'read index received' (duration: 16.983419ms)","trace[1115094780] 'applied index is now lower than readState.Index' (duration: 190.89239ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:14.382413Z","caller":"traceutil/trace.go:171","msg":"trace[1827459016] transaction","detail":"{read_only:false; response_revision:25253; number_of_response:1; }","duration":"262.998719ms","start":"2026-09-22T02:41:14.119389Z","end":"2026-09-22T02:41:14.382388Z","steps":["trace[1827459016] 'process raft request' (duration: 131.515515ms)","trace[1827459016] 'compare' (duration: 128.731922ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:14.382469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.409781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:41:14.382498Z","caller":"traceutil/trace.go:171","msg":"trace[1237471443] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:25253; }","duration":"152.471393ms","start":"2026-09-22T02:41:14.230016Z","end":"2026-09-22T02:41:14.382487Z","steps":["trace[1237471443] 'agreement among raft nodes before linearized reading' (duration: 152.412012ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:14.382532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.310449ms","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-09-22T02:41:14.382558Z","caller":"traceutil/trace.go:171","msg":"trace[814133256] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25253; }","duration":"139.37581ms","start":"2026-09-22T02:41:14.243173Z","end":"2026-09-22T02:41:14.382549Z","steps":["trace[814133256] 'agreement among raft nodes before linearized reading' (duration: 139.273208ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:14.383235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.511823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-22T02:41:14.383303Z","caller":"traceutil/trace.go:171","msg":"trace[503673032] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25253; }","duration":"208.828449ms","start":"2026-09-22T02:41:14.174457Z","end":"2026-09-22T02:41:14.383285Z","steps":["trace[503673032] 'agreement among raft nodes before linearized reading' (duration: 207.95649ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:14.655182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.575958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:41:14.655305Z","caller":"traceutil/trace.go:171","msg":"trace[1813707823] transaction","detail":"{read_only:false; response_revision:25254; number_of_response:1; }","duration":"268.241736ms","start":"2026-09-22T02:41:14.387017Z","end":"2026-09-22T02:41:14.655259Z","steps":["trace[1813707823] 'process raft request' (duration: 131.511714ms)","trace[1813707823] 'compare' (duration: 136.014086ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:14.673319Z","caller":"traceutil/trace.go:171","msg":"trace[147197659] linearizableReadLoop","detail":"{readStateIndex:26084; appliedIndex:26082; }","duration":"281.41471ms","start":"2026-09-22T02:41:14.391885Z","end":"2026-09-22T02:41:14.673299Z","steps":["trace[147197659] 'read index received' (duration: 126.658496ms)","trace[147197659] 'applied index is now lower than readState.Index' (duration: 154.755244ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:14.673594Z","caller":"traceutil/trace.go:171","msg":"trace[1531054392] transaction","detail":"{read_only:false; response_revision:25255; number_of_response:1; }","duration":"283.623459ms","start":"2026-09-22T02:41:14.389961Z","end":"2026-09-22T02:41:14.673584Z","steps":["trace[1531054392] 'process raft request' (duration: 283.21334ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:14.673779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.87464ms","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:6"} {"level":"info","ts":"2026-09-22T02:41:14.673811Z","caller":"traceutil/trace.go:171","msg":"trace[1820887489] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25255; }","duration":"281.938901ms","start":"2026-09-22T02:41:14.391861Z","end":"2026-09-22T02:41:14.6738Z","steps":["trace[1820887489] 'agreement among raft nodes before linearized reading' (duration: 281.872499ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:14.673919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.858097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:41:14.673941Z","caller":"traceutil/trace.go:171","msg":"trace[1226461078] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:25255; }","duration":"185.92375ms","start":"2026-09-22T02:41:14.488012Z","end":"2026-09-22T02:41:14.673935Z","steps":["trace[1226461078] 'agreement among raft nodes before linearized reading' (duration: 185.882909ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:14.8552Z","caller":"traceutil/trace.go:171","msg":"trace[740189171] linearizableReadLoop","detail":"{readStateIndex:26085; appliedIndex:26084; }","duration":"109.858432ms","start":"2026-09-22T02:41:14.745321Z","end":"2026-09-22T02:41:14.85518Z","steps":["trace[740189171] 'read index received' (duration: 109.645096ms)","trace[740189171] 'applied index is now lower than readState.Index' (duration: 212.616µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:14.855298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.959624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:14.855315Z","caller":"traceutil/trace.go:171","msg":"trace[600328812] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:25256; }","duration":"110.028676ms","start":"2026-09-22T02:41:14.74528Z","end":"2026-09-22T02:41:14.855309Z","steps":["trace[600328812] 'agreement among raft nodes before linearized reading' (duration: 109.977245ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:14.855288Z","caller":"traceutil/trace.go:171","msg":"trace[1300869508] transaction","detail":"{read_only:false; response_revision:25256; number_of_response:1; }","duration":"140.423165ms","start":"2026-09-22T02:41:14.714828Z","end":"2026-09-22T02:41:14.855251Z","steps":["trace[1300869508] 'process raft request' (duration: 140.189418ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:18.990797Z","caller":"traceutil/trace.go:171","msg":"trace[367668399] linearizableReadLoop","detail":"{readStateIndex:26115; appliedIndex:26114; }","duration":"237.904489ms","start":"2026-09-22T02:41:18.752861Z","end":"2026-09-22T02:41:18.990766Z","steps":["trace[367668399] 'read index received' (duration: 224.707624ms)","trace[367668399] 'applied index is now lower than readState.Index' (duration: 13.195645ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:18.990873Z","caller":"traceutil/trace.go:171","msg":"trace[621149880] transaction","detail":"{read_only:false; response_revision:25285; number_of_response:1; }","duration":"274.466404ms","start":"2026-09-22T02:41:18.71634Z","end":"2026-09-22T02:41:18.990806Z","steps":["trace[621149880] 'process raft request' (duration: 261.212158ms)","trace[621149880] 'compare' (duration: 12.759976ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:18.990948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.066293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:41:18.991007Z","caller":"traceutil/trace.go:171","msg":"trace[1728427481] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:25285; }","duration":"238.186446ms","start":"2026-09-22T02:41:18.752808Z","end":"2026-09-22T02:41:18.990994Z","steps":["trace[1728427481] 'agreement among raft nodes before linearized reading' (duration: 238.053493ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:19.046503Z","caller":"traceutil/trace.go:171","msg":"trace[1588630500] transaction","detail":"{read_only:false; response_revision:25286; number_of_response:1; }","duration":"177.605214ms","start":"2026-09-22T02:41:18.868873Z","end":"2026-09-22T02:41:19.046478Z","steps":["trace[1588630500] 'process raft request' (duration: 177.406259ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:19.046595Z","caller":"traceutil/trace.go:171","msg":"trace[1618404889] transaction","detail":"{read_only:false; response_revision:25287; number_of_response:1; }","duration":"167.015177ms","start":"2026-09-22T02:41:18.879521Z","end":"2026-09-22T02:41:19.046536Z","steps":["trace[1618404889] 'process raft request' (duration: 166.885244ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:19.273297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.325702ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:41:19.273561Z","caller":"traceutil/trace.go:171","msg":"trace[858508273] transaction","detail":"{read_only:false; response_revision:25289; number_of_response:1; }","duration":"193.691523ms","start":"2026-09-22T02:41:19.079847Z","end":"2026-09-22T02:41:19.273539Z","steps":["trace[858508273] 'process raft request' (duration: 193.58783ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:19.273572Z","caller":"traceutil/trace.go:171","msg":"trace[1338884968] transaction","detail":"{read_only:false; response_revision:25288; number_of_response:1; }","duration":"221.163875ms","start":"2026-09-22T02:41:19.052361Z","end":"2026-09-22T02:41:19.273525Z","steps":["trace[1338884968] 'process raft request' (duration: 63.451285ms)","trace[1338884968] 'compare' (duration: 157.110037ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:19.542847Z","caller":"traceutil/trace.go:171","msg":"trace[603251637] transaction","detail":"{read_only:false; response_revision:25290; number_of_response:1; }","duration":"124.340675ms","start":"2026-09-22T02:41:19.418466Z","end":"2026-09-22T02:41:19.542807Z","steps":["trace[603251637] 'process raft request' (duration: 124.061909ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:19.822369Z","caller":"traceutil/trace.go:171","msg":"trace[1625565102] linearizableReadLoop","detail":"{readStateIndex:26121; appliedIndex:26120; }","duration":"252.801292ms","start":"2026-09-22T02:41:19.569543Z","end":"2026-09-22T02:41:19.822344Z","steps":["trace[1625565102] 'read index received' (duration: 202.260214ms)","trace[1625565102] 'applied index is now lower than readState.Index' (duration: 50.539758ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:19.822406Z","caller":"traceutil/trace.go:171","msg":"trace[1207508842] transaction","detail":"{read_only:false; response_revision:25291; number_of_response:1; }","duration":"275.347645ms","start":"2026-09-22T02:41:19.547023Z","end":"2026-09-22T02:41:19.822371Z","steps":["trace[1207508842] 'process raft request' (duration: 224.684785ms)","trace[1207508842] 'compare' (duration: 50.313753ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:19.822581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.550728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:41:19.822604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.043147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-22T02:41:19.822671Z","caller":"traceutil/trace.go:171","msg":"trace[1508451349] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25291; }","duration":"253.16457ms","start":"2026-09-22T02:41:19.569495Z","end":"2026-09-22T02:41:19.82266Z","steps":["trace[1508451349] 'agreement among raft nodes before linearized reading' (duration: 252.970775ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:19.822673Z","caller":"traceutil/trace.go:171","msg":"trace[489666674] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:25291; }","duration":"188.678401ms","start":"2026-09-22T02:41:19.633953Z","end":"2026-09-22T02:41:19.822631Z","steps":["trace[489666674] 'agreement among raft nodes before linearized reading' (duration: 188.555818ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:20.103115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.606148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:41:20.103409Z","caller":"traceutil/trace.go:171","msg":"trace[1861815173] transaction","detail":"{read_only:false; response_revision:25292; number_of_response:1; }","duration":"276.05489ms","start":"2026-09-22T02:41:19.827309Z","end":"2026-09-22T02:41:20.103364Z","steps":["trace[1861815173] 'process raft request' (duration: 138.706776ms)","trace[1861815173] 'compare' (duration: 136.417504ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:20.103506Z","caller":"traceutil/trace.go:171","msg":"trace[52721809] transaction","detail":"{read_only:false; response_revision:25293; number_of_response:1; }","duration":"274.586847ms","start":"2026-09-22T02:41:19.828893Z","end":"2026-09-22T02:41:20.10348Z","steps":["trace[52721809] 'process raft request' (duration: 274.370082ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:20.103694Z","caller":"traceutil/trace.go:171","msg":"trace[1973518017] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"187.260909ms","start":"2026-09-22T02:41:19.916403Z","end":"2026-09-22T02:41:20.103664Z","steps":["trace[1973518017] 'process raft request' (duration: 186.946752ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:20.326518Z","caller":"traceutil/trace.go:171","msg":"trace[829794004] transaction","detail":"{read_only:false; response_revision:25295; number_of_response:1; }","duration":"190.129002ms","start":"2026-09-22T02:41:20.13635Z","end":"2026-09-22T02:41:20.326479Z","steps":["trace[829794004] 'process raft request' (duration: 127.854442ms)","trace[829794004] 'compare' (duration: 62.100956ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:20.584252Z","caller":"traceutil/trace.go:171","msg":"trace[1822768719] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"374.62834ms","start":"2026-09-22T02:41:20.209589Z","end":"2026-09-22T02:41:20.584217Z","steps":["trace[1822768719] 'process raft request' (duration: 318.421315ms)","trace[1822768719] 'compare' (duration: 56.00894ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:20.584414Z","caller":"traceutil/trace.go:171","msg":"trace[1831613991] linearizableReadLoop","detail":"{readStateIndex:26127; appliedIndex:26125; }","duration":"217.699018ms","start":"2026-09-22T02:41:20.36669Z","end":"2026-09-22T02:41:20.584389Z","steps":["trace[1831613991] 'read index received' (duration: 161.231228ms)","trace[1831613991] 'applied index is now lower than readState.Index' (duration: 56.46663ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:20.584443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:20.20955Z","time spent":"374.790993ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:20.584525Z","caller":"traceutil/trace.go:171","msg":"trace[1693189168] transaction","detail":"{read_only:false; response_revision:25297; number_of_response:1; }","duration":"373.004053ms","start":"2026-09-22T02:41:20.211508Z","end":"2026-09-22T02:41:20.584512Z","steps":["trace[1693189168] 'process raft request' (duration: 372.648855ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:20.584627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:20.211483Z","time spent":"373.064905ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:20.58484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.155188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:20.584869Z","caller":"traceutil/trace.go:171","msg":"trace[25934871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25297; }","duration":"218.249691ms","start":"2026-09-22T02:41:20.36661Z","end":"2026-09-22T02:41:20.58486Z","steps":["trace[25934871] 'agreement among raft nodes before linearized reading' (duration: 218.193259ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:20.933102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.664051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:41:20.933248Z","caller":"traceutil/trace.go:171","msg":"trace[1398985903] linearizableReadLoop","detail":"{readStateIndex:26128; appliedIndex:26127; }","duration":"163.532269ms","start":"2026-09-22T02:41:20.769659Z","end":"2026-09-22T02:41:20.933191Z","steps":["trace[1398985903] 'read index received' (duration: 34.341µs)","trace[1398985903] 'applied index is now lower than readState.Index' (duration: 163.496568ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:20.933664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.001269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.96\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-22T02:41:20.933702Z","caller":"traceutil/trace.go:171","msg":"trace[1518252687] range","detail":"{range_begin:/registry/masterleases/162.253.55.96; range_end:; response_count:1; response_revision:25298; }","duration":"164.089411ms","start":"2026-09-22T02:41:20.769603Z","end":"2026-09-22T02:41:20.933692Z","steps":["trace[1518252687] 'agreement among raft nodes before linearized reading' (duration: 163.866586ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:20.933792Z","caller":"traceutil/trace.go:171","msg":"trace[1865747485] transaction","detail":"{read_only:false; response_revision:25298; number_of_response:1; }","duration":"247.083253ms","start":"2026-09-22T02:41:20.686669Z","end":"2026-09-22T02:41:20.933753Z","steps":["trace[1865747485] 'process raft request' (duration: 61.672056ms)","trace[1865747485] 'compare' (duration: 184.040967ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:21.177103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.052104ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-09-22T02:41:21.177481Z","caller":"traceutil/trace.go:171","msg":"trace[212741787] linearizableReadLoop","detail":"{readStateIndex:26129; appliedIndex:26128; }","duration":"181.167552ms","start":"2026-09-22T02:41:20.996284Z","end":"2026-09-22T02:41:21.177452Z","steps":["trace[212741787] 'read index received' (duration: 36.661018ms)","trace[212741787] 'applied index is now lower than readState.Index' (duration: 144.504024ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:21.17754Z","caller":"traceutil/trace.go:171","msg":"trace[407480943] transaction","detail":"{read_only:false; response_revision:25299; number_of_response:1; }","duration":"125.424318ms","start":"2026-09-22T02:41:21.05209Z","end":"2026-09-22T02:41:21.177515Z","steps":["trace[407480943] 'process raft request' (duration: 125.187393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:21.177779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.758235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:21.177844Z","caller":"traceutil/trace.go:171","msg":"trace[614440086] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:25300; }","duration":"137.882228ms","start":"2026-09-22T02:41:21.039947Z","end":"2026-09-22T02:41:21.17783Z","steps":["trace[614440086] 'agreement among raft nodes before linearized reading' (duration: 137.733043ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:21.177828Z","caller":"traceutil/trace.go:171","msg":"trace[1046658852] transaction","detail":"{read_only:false; response_revision:25300; number_of_response:1; }","duration":"125.695705ms","start":"2026-09-22T02:41:21.052109Z","end":"2026-09-22T02:41:21.177805Z","steps":["trace[1046658852] 'process raft request' (duration: 125.342576ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:21.177862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.565862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-22T02:41:21.177936Z","caller":"traceutil/trace.go:171","msg":"trace[1284849719] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25300; }","duration":"181.688595ms","start":"2026-09-22T02:41:20.99623Z","end":"2026-09-22T02:41:21.177919Z","steps":["trace[1284849719] 'agreement among raft nodes before linearized reading' (duration: 181.363827ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:21.334428Z","caller":"traceutil/trace.go:171","msg":"trace[1347243577] transaction","detail":"{read_only:false; response_revision:25301; number_of_response:1; }","duration":"154.762903ms","start":"2026-09-22T02:41:21.179616Z","end":"2026-09-22T02:41:21.334379Z","steps":["trace[1347243577] 'process raft request' (duration: 143.326238ms)","trace[1347243577] 'compare' (duration: 11.009626ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:21.43116Z","caller":"traceutil/trace.go:171","msg":"trace[2012096331] linearizableReadLoop","detail":"{readStateIndex:26133; appliedIndex:26131; }","duration":"245.273343ms","start":"2026-09-22T02:41:21.185849Z","end":"2026-09-22T02:41:21.431123Z","steps":["trace[2012096331] 'read index received' (duration: 137.085729ms)","trace[2012096331] 'applied index is now lower than readState.Index' (duration: 108.186184ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:21.431694Z","caller":"traceutil/trace.go:171","msg":"trace[1525033810] transaction","detail":"{read_only:false; response_revision:25302; number_of_response:1; }","duration":"250.102711ms","start":"2026-09-22T02:41:21.181525Z","end":"2026-09-22T02:41:21.431627Z","steps":["trace[1525033810] 'process raft request' (duration: 249.337654ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:21.43177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.890367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-22T02:41:21.431814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.098394ms","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-09-22T02:41:21.431868Z","caller":"traceutil/trace.go:171","msg":"trace[2138406829] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:25303; }","duration":"246.051271ms","start":"2026-09-22T02:41:21.185788Z","end":"2026-09-22T02:41:21.43184Z","steps":["trace[2138406829] 'agreement among raft nodes before linearized reading' (duration: 245.780245ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:21.431718Z","caller":"traceutil/trace.go:171","msg":"trace[429887340] transaction","detail":"{read_only:false; response_revision:25303; number_of_response:1; }","duration":"150.570831ms","start":"2026-09-22T02:41:21.281112Z","end":"2026-09-22T02:41:21.431683Z","steps":["trace[429887340] 'process raft request' (duration: 149.948197ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:21.431885Z","caller":"traceutil/trace.go:171","msg":"trace[1389144382] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25303; }","duration":"152.249327ms","start":"2026-09-22T02:41:21.279616Z","end":"2026-09-22T02:41:21.431865Z","steps":["trace[1389144382] 'agreement among raft nodes before linearized reading' (duration: 152.004631ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:21.43227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.154431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:21.432362Z","caller":"traceutil/trace.go:171","msg":"trace[806133617] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:25303; }","duration":"124.301583ms","start":"2026-09-22T02:41:21.30804Z","end":"2026-09-22T02:41:21.432342Z","steps":["trace[806133617] 'agreement among raft nodes before linearized reading' (duration: 124.13224ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:21.595318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.428242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-22T02:41:21.595384Z","caller":"traceutil/trace.go:171","msg":"trace[426023038] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:25303; }","duration":"161.555715ms","start":"2026-09-22T02:41:21.433815Z","end":"2026-09-22T02:41:21.59537Z","steps":["trace[426023038] 'range keys from in-memory index tree' (duration: 161.328111ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:21.596268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.767669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:41:21.596366Z","caller":"traceutil/trace.go:171","msg":"trace[400643354] transaction","detail":"{read_only:false; response_revision:25304; number_of_response:1; }","duration":"159.653313ms","start":"2026-09-22T02:41:21.436699Z","end":"2026-09-22T02:41:21.596352Z","steps":["trace[400643354] 'compare' (duration: 152.231178ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:22.347324Z","caller":"traceutil/trace.go:171","msg":"trace[171732488] transaction","detail":"{read_only:false; response_revision:25308; number_of_response:1; }","duration":"196.967434ms","start":"2026-09-22T02:41:22.150335Z","end":"2026-09-22T02:41:22.347302Z","steps":["trace[171732488] 'process raft request' (duration: 196.839101ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:22.405179Z","caller":"traceutil/trace.go:171","msg":"trace[59498696] linearizableReadLoop","detail":"{readStateIndex:26140; appliedIndex:26138; }","duration":"165.502123ms","start":"2026-09-22T02:41:22.239646Z","end":"2026-09-22T02:41:22.405148Z","steps":["trace[59498696] 'read index received' (duration: 107.523059ms)","trace[59498696] 'applied index is now lower than readState.Index' (duration: 57.977884ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:22.40528Z","caller":"traceutil/trace.go:171","msg":"trace[313518931] transaction","detail":"{read_only:false; response_revision:25309; number_of_response:1; }","duration":"251.310168ms","start":"2026-09-22T02:41:22.153887Z","end":"2026-09-22T02:41:22.405197Z","steps":["trace[313518931] 'process raft request' (duration: 251.083913ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:22.405898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.247029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274424"} {"level":"info","ts":"2026-09-22T02:41:22.405943Z","caller":"traceutil/trace.go:171","msg":"trace[1713398062] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25310; }","duration":"166.349692ms","start":"2026-09-22T02:41:22.23958Z","end":"2026-09-22T02:41:22.40593Z","steps":["trace[1713398062] 'agreement among raft nodes before linearized reading' (duration: 165.898562ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:22.406139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.34098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:22.406244Z","caller":"traceutil/trace.go:171","msg":"trace[741671793] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:25310; }","duration":"100.429531ms","start":"2026-09-22T02:41:22.305771Z","end":"2026-09-22T02:41:22.4062Z","steps":["trace[741671793] 'agreement among raft nodes before linearized reading' (duration: 100.214727ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:22.694342Z","caller":"traceutil/trace.go:171","msg":"trace[1802968438] transaction","detail":"{read_only:false; response_revision:25311; number_of_response:1; }","duration":"103.424388ms","start":"2026-09-22T02:41:22.590894Z","end":"2026-09-22T02:41:22.694318Z","steps":["trace[1802968438] 'process raft request' (duration: 103.268874ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:22.73415Z","caller":"traceutil/trace.go:171","msg":"trace[1522700755] transaction","detail":"{read_only:false; response_revision:25312; number_of_response:1; }","duration":"142.400118ms","start":"2026-09-22T02:41:22.591726Z","end":"2026-09-22T02:41:22.734126Z","steps":["trace[1522700755] 'process raft request' (duration: 142.253835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:23.08747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.050668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-22T02:41:23.08755Z","caller":"traceutil/trace.go:171","msg":"trace[1338248544] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25312; }","duration":"150.178291ms","start":"2026-09-22T02:41:22.937352Z","end":"2026-09-22T02:41:23.087531Z","steps":["trace[1338248544] 'range keys from in-memory index tree' (duration: 149.879005ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:23.450477Z","caller":"traceutil/trace.go:171","msg":"trace[1642499468] transaction","detail":"{read_only:false; response_revision:25313; number_of_response:1; }","duration":"356.071806ms","start":"2026-09-22T02:41:23.094379Z","end":"2026-09-22T02:41:23.45045Z","steps":["trace[1642499468] 'process raft request' (duration: 355.929852ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:23.450619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:23.094356Z","time spent":"356.190818ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:23.483186Z","caller":"traceutil/trace.go:171","msg":"trace[236910734] transaction","detail":"{read_only:false; response_revision:25314; number_of_response:1; }","duration":"299.498453ms","start":"2026-09-22T02:41:23.183657Z","end":"2026-09-22T02:41:23.483156Z","steps":["trace[236910734] 'process raft request' (duration: 299.330049ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:23.483381Z","caller":"traceutil/trace.go:171","msg":"trace[1422163630] linearizableReadLoop","detail":"{readStateIndex:26146; appliedIndex:26143; }","duration":"116.186373ms","start":"2026-09-22T02:41:23.367179Z","end":"2026-09-22T02:41:23.483365Z","steps":["trace[1422163630] 'read index received' (duration: 83.119135ms)","trace[1422163630] 'applied index is now lower than readState.Index' (duration: 33.066308ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:23.483554Z","caller":"traceutil/trace.go:171","msg":"trace[724824419] transaction","detail":"{read_only:false; response_revision:25315; number_of_response:1; }","duration":"299.600136ms","start":"2026-09-22T02:41:23.183776Z","end":"2026-09-22T02:41:23.483376Z","steps":["trace[724824419] 'process raft request' (duration: 299.339359ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:23.483561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.343417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:23.483673Z","caller":"traceutil/trace.go:171","msg":"trace[1683984719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25316; }","duration":"116.518351ms","start":"2026-09-22T02:41:23.367134Z","end":"2026-09-22T02:41:23.483653Z","steps":["trace[1683984719] 'agreement among raft nodes before linearized reading' (duration: 116.317356ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:23.712574Z","caller":"traceutil/trace.go:171","msg":"trace[1258639855] linearizableReadLoop","detail":"{readStateIndex:26148; appliedIndex:26147; }","duration":"219.602679ms","start":"2026-09-22T02:41:23.492942Z","end":"2026-09-22T02:41:23.712545Z","steps":["trace[1258639855] 'read index received' (duration: 132.717781ms)","trace[1258639855] 'applied index is now lower than readState.Index' (duration: 86.883788ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:23.712675Z","caller":"traceutil/trace.go:171","msg":"trace[1560858697] transaction","detail":"{read_only:false; response_revision:25317; number_of_response:1; }","duration":"222.391882ms","start":"2026-09-22T02:41:23.490215Z","end":"2026-09-22T02:41:23.712607Z","steps":["trace[1560858697] 'process raft request' (duration: 135.410281ms)","trace[1560858697] 'compare' (duration: 86.649913ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:23.712714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.758333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:23.712753Z","caller":"traceutil/trace.go:171","msg":"trace[881840866] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:25317; }","duration":"219.848155ms","start":"2026-09-22T02:41:23.492894Z","end":"2026-09-22T02:41:23.712742Z","steps":["trace[881840866] 'agreement among raft nodes before linearized reading' (duration: 219.730492ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:23.712921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.607092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-22T02:41:23.712987Z","caller":"traceutil/trace.go:171","msg":"trace[2033128736] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25317; }","duration":"112.721085ms","start":"2026-09-22T02:41:23.600247Z","end":"2026-09-22T02:41:23.712968Z","steps":["trace[2033128736] 'agreement among raft nodes before linearized reading' (duration: 112.48858ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:35.77414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.15965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:35.774236Z","caller":"traceutil/trace.go:171","msg":"trace[161051692] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:25401; }","duration":"318.305104ms","start":"2026-09-22T02:41:35.45591Z","end":"2026-09-22T02:41:35.774215Z","steps":["trace[161051692] 'count revisions from in-memory index tree' (duration: 318.039097ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:35.774281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:35.455891Z","time spent":"318.378975ms","remote":"127.0.0.1:36368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:41:35.774313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.072287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:35.774409Z","caller":"traceutil/trace.go:171","msg":"trace[457069434] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:25401; }","duration":"253.2324ms","start":"2026-09-22T02:41:35.521152Z","end":"2026-09-22T02:41:35.774384Z","steps":["trace[457069434] 'count revisions from in-memory index tree' (duration: 252.916684ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:35.774686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.319109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:35.774721Z","caller":"traceutil/trace.go:171","msg":"trace[1417854880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25401; }","duration":"407.38572ms","start":"2026-09-22T02:41:35.367325Z","end":"2026-09-22T02:41:35.774711Z","steps":["trace[1417854880] 'range keys from in-memory index tree' (duration: 407.248988ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:35.774746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:35.367308Z","time spent":"407.432251ms","remote":"127.0.0.1:37026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T02:41:35.77491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.6823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-22T02:41:35.774965Z","caller":"traceutil/trace.go:171","msg":"trace[1194541014] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25401; }","duration":"240.769263ms","start":"2026-09-22T02:41:35.534179Z","end":"2026-09-22T02:41:35.774949Z","steps":["trace[1194541014] 'range keys from in-memory index tree' (duration: 239.662748ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:35.775859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.345858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-22T02:41:35.775939Z","caller":"traceutil/trace.go:171","msg":"trace[582733100] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25401; }","duration":"579.471201ms","start":"2026-09-22T02:41:35.196447Z","end":"2026-09-22T02:41:35.775919Z","steps":["trace[582733100] 'count revisions from in-memory index tree' (duration: 579.179065ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:35.775994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:35.196427Z","time spent":"579.549882ms","remote":"127.0.0.1:37100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2638,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-09-22T02:41:35.783044Z","caller":"traceutil/trace.go:171","msg":"trace[469012850] transaction","detail":"{read_only:false; response_revision:25402; number_of_response:1; }","duration":"246.802747ms","start":"2026-09-22T02:41:35.536225Z","end":"2026-09-22T02:41:35.783027Z","steps":["trace[469012850] 'process raft request' (duration: 246.661784ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:36.375967Z","caller":"traceutil/trace.go:171","msg":"trace[201586658] linearizableReadLoop","detail":"{readStateIndex:26237; appliedIndex:26234; }","duration":"599.499237ms","start":"2026-09-22T02:41:35.776451Z","end":"2026-09-22T02:41:36.37595Z","steps":["trace[201586658] 'read index received' (duration: 6.526765ms)","trace[201586658] 'applied index is now lower than readState.Index' (duration: 592.971652ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:36.376009Z","caller":"traceutil/trace.go:171","msg":"trace[1184236640] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"817.911811ms","start":"2026-09-22T02:41:35.558046Z","end":"2026-09-22T02:41:36.375958Z","steps":["trace[1184236640] 'process raft request' (duration: 817.179875ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:36.376123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.65946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:36.376152Z","caller":"traceutil/trace.go:171","msg":"trace[495960605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25405; }","duration":"599.744353ms","start":"2026-09-22T02:41:35.776399Z","end":"2026-09-22T02:41:36.376143Z","steps":["trace[495960605] 'agreement among raft nodes before linearized reading' (duration: 599.683592ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:36.376176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:35.776381Z","time spent":"599.789044ms","remote":"127.0.0.1:37030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T02:41:36.376183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:35.558025Z","time spent":"818.072875ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:36.376047Z","caller":"traceutil/trace.go:171","msg":"trace[1119385536] transaction","detail":"{read_only:false; response_revision:25404; number_of_response:1; }","duration":"810.502137ms","start":"2026-09-22T02:41:35.565536Z","end":"2026-09-22T02:41:36.376038Z","steps":["trace[1119385536] 'process raft request' (duration: 810.305522ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:36.37647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:35.565511Z","time spent":"810.917485ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:36.376071Z","caller":"traceutil/trace.go:171","msg":"trace[246235646] transaction","detail":"{read_only:false; response_revision:25405; number_of_response:1; }","duration":"596.395798ms","start":"2026-09-22T02:41:35.77967Z","end":"2026-09-22T02:41:36.376066Z","steps":["trace[246235646] 'process raft request' (duration: 596.245875ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:36.376596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:35.779643Z","time spent":"596.891949ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:36.395844Z","caller":"traceutil/trace.go:171","msg":"trace[1449536158] transaction","detail":"{read_only:false; response_revision:25406; number_of_response:1; }","duration":"412.113326ms","start":"2026-09-22T02:41:35.983693Z","end":"2026-09-22T02:41:36.395806Z","steps":["trace[1449536158] 'process raft request' (duration: 411.780179ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:36.395957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:35.983675Z","time spent":"412.217158ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:36.685991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.366609ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-22T02:41:36.686201Z","caller":"traceutil/trace.go:171","msg":"trace[878034052] linearizableReadLoop","detail":"{readStateIndex:26240; appliedIndex:26238; }","duration":"310.169632ms","start":"2026-09-22T02:41:36.37601Z","end":"2026-09-22T02:41:36.68618Z","steps":["trace[878034052] 'read index received' (duration: 19.27179ms)","trace[878034052] 'applied index is now lower than readState.Index' (duration: 290.896332ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:36.686314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.780371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:36.686347Z","caller":"traceutil/trace.go:171","msg":"trace[1186362821] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:25406; }","duration":"412.857643ms","start":"2026-09-22T02:41:36.273479Z","end":"2026-09-22T02:41:36.686337Z","steps":["trace[1186362821] 'agreement among raft nodes before linearized reading' (duration: 412.783351ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:36.686382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.27346Z","time spent":"412.913485ms","remote":"127.0.0.1:37254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:41:36.68664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.190513ms","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:6"} {"level":"info","ts":"2026-09-22T02:41:36.686704Z","caller":"traceutil/trace.go:171","msg":"trace[1149715662] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25406; }","duration":"747.339336ms","start":"2026-09-22T02:41:35.939353Z","end":"2026-09-22T02:41:36.686693Z","steps":["trace[1149715662] 'agreement among raft nodes before linearized reading' (duration: 747.215103ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:36.686737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:35.939331Z","time spent":"747.396568ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:41:36.687104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.641652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-09-22T02:41:36.687205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.06262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-22T02:41:36.68722Z","caller":"traceutil/trace.go:171","msg":"trace[1194468561] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25406; }","duration":"833.784916ms","start":"2026-09-22T02:41:35.853394Z","end":"2026-09-22T02:41:36.687179Z","steps":["trace[1194468561] 'agreement among raft nodes before linearized reading' (duration: 833.474989ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:36.687266Z","caller":"traceutil/trace.go:171","msg":"trace[573482740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25406; }","duration":"760.139761ms","start":"2026-09-22T02:41:35.927111Z","end":"2026-09-22T02:41:36.687251Z","steps":["trace[573482740] 'agreement among raft nodes before linearized reading' (duration: 759.982468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:36.687282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:35.853375Z","time spent":"833.891348ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-22T02:41:36.687302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:35.927106Z","time spent":"760.188233ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-22T02:41:36.687321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.172719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-22T02:41:36.687418Z","caller":"traceutil/trace.go:171","msg":"trace[724959102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25406; }","duration":"767.307492ms","start":"2026-09-22T02:41:35.920086Z","end":"2026-09-22T02:41:36.687394Z","steps":["trace[724959102] 'agreement among raft nodes before linearized reading' (duration: 767.028466ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:36.687475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:35.920069Z","time spent":"767.393054ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-22T02:41:36.687661Z","caller":"traceutil/trace.go:171","msg":"trace[774523505] transaction","detail":"{read_only:false; response_revision:25407; number_of_response:1; }","duration":"210.108869ms","start":"2026-09-22T02:41:36.477487Z","end":"2026-09-22T02:41:36.687596Z","steps":["trace[774523505] 'process raft request' (duration: 208.637206ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:37.168078Z","caller":"traceutil/trace.go:171","msg":"trace[148865635] linearizableReadLoop","detail":"{readStateIndex:26242; appliedIndex:26241; }","duration":"481.818262ms","start":"2026-09-22T02:41:36.686237Z","end":"2026-09-22T02:41:37.168055Z","steps":["trace[148865635] 'read index received' (duration: 481.687908ms)","trace[148865635] 'applied index is now lower than readState.Index' (duration: 129.494µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:37.168121Z","caller":"traceutil/trace.go:171","msg":"trace[1391246541] transaction","detail":"{read_only:false; response_revision:25408; number_of_response:1; }","duration":"645.571526ms","start":"2026-09-22T02:41:36.522513Z","end":"2026-09-22T02:41:37.168084Z","steps":["trace[1391246541] 'process raft request' (duration: 645.398711ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:37.168263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.25618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-09-22T02:41:37.168266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.522479Z","time spent":"645.697959ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:37.168297Z","caller":"traceutil/trace.go:171","msg":"trace[1215281948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25408; }","duration":"645.312261ms","start":"2026-09-22T02:41:36.522971Z","end":"2026-09-22T02:41:37.168283Z","steps":["trace[1215281948] 'agreement among raft nodes before linearized reading' (duration: 645.189488ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:37.168299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.147184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:41:37.168324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.522961Z","time spent":"645.355671ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-22T02:41:37.168348Z","caller":"traceutil/trace.go:171","msg":"trace[1689702851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25408; }","duration":"787.239667ms","start":"2026-09-22T02:41:36.381094Z","end":"2026-09-22T02:41:37.168333Z","steps":["trace[1689702851] 'agreement among raft nodes before linearized reading' (duration: 787.099694ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:37.168379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.381074Z","time spent":"787.298298ms","remote":"127.0.0.1:37026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T02:41:37.168361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.06827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-22T02:41:37.168433Z","caller":"traceutil/trace.go:171","msg":"trace[64074698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25408; }","duration":"604.172712ms","start":"2026-09-22T02:41:36.564243Z","end":"2026-09-22T02:41:37.168416Z","steps":["trace[64074698] 'agreement among raft nodes before linearized reading' (duration: 603.908817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:37.168486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.564236Z","time spent":"604.237794ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-22T02:41:37.250973Z","caller":"traceutil/trace.go:171","msg":"trace[979028728] transaction","detail":"{read_only:false; response_revision:25409; number_of_response:1; }","duration":"558.169205ms","start":"2026-09-22T02:41:36.692766Z","end":"2026-09-22T02:41:37.250935Z","steps":["trace[979028728] 'process raft request' (duration: 557.899759ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:37.251029Z","caller":"traceutil/trace.go:171","msg":"trace[1775119457] transaction","detail":"{read_only:false; response_revision:25411; number_of_response:1; }","duration":"558.203605ms","start":"2026-09-22T02:41:36.692816Z","end":"2026-09-22T02:41:37.25102Z","steps":["trace[1775119457] 'process raft request' (duration: 558.052032ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:37.251021Z","caller":"traceutil/trace.go:171","msg":"trace[1840959584] transaction","detail":"{read_only:false; response_revision:25410; number_of_response:1; }","duration":"558.226696ms","start":"2026-09-22T02:41:36.692759Z","end":"2026-09-22T02:41:37.250986Z","steps":["trace[1840959584] 'process raft request' (duration: 558.047373ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:37.25111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.692791Z","time spent":"558.266417ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:37.251111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.692747Z","time spent":"558.294198ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:37.250972Z","caller":"traceutil/trace.go:171","msg":"trace[1772072006] transaction","detail":"{read_only:false; response_revision:25412; number_of_response:1; }","duration":"477.925724ms","start":"2026-09-22T02:41:36.773031Z","end":"2026-09-22T02:41:37.250957Z","steps":["trace[1772072006] 'process raft request' (duration: 477.869193ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:37.251195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.69273Z","time spent":"558.365389ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:37.251235Z","caller":"traceutil/trace.go:171","msg":"trace[499601943] transaction","detail":"{read_only:false; response_revision:25413; number_of_response:1; }","duration":"442.845182ms","start":"2026-09-22T02:41:36.808377Z","end":"2026-09-22T02:41:37.251223Z","steps":["trace[499601943] 'process raft request' (duration: 442.564655ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:37.251289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.808358Z","time spent":"442.899383ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:37.25131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.773008Z","time spent":"478.229231ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:37.251247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.043944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:37.251403Z","caller":"traceutil/trace.go:171","msg":"trace[1967393570] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25413; }","duration":"412.235698ms","start":"2026-09-22T02:41:36.839153Z","end":"2026-09-22T02:41:37.251389Z","steps":["trace[1967393570] 'agreement among raft nodes before linearized reading' (duration: 412.059864ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:37.251654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:36.839132Z","time spent":"412.404873ms","remote":"127.0.0.1:50386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-09-22T02:41:37.751104Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7531884202572600923,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-22T02:41:37.950069Z","caller":"traceutil/trace.go:171","msg":"trace[877690868] transaction","detail":"{read_only:false; response_revision:25414; number_of_response:1; }","duration":"776.941848ms","start":"2026-09-22T02:41:37.173106Z","end":"2026-09-22T02:41:37.950048Z","steps":["trace[877690868] 'process raft request' (duration: 776.770134ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:37.950249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.173086Z","time spent":"777.090391ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:37.950383Z","caller":"traceutil/trace.go:171","msg":"trace[786553] transaction","detail":"{read_only:false; response_revision:25415; number_of_response:1; }","duration":"777.161583ms","start":"2026-09-22T02:41:37.173195Z","end":"2026-09-22T02:41:37.950356Z","steps":["trace[786553] 'process raft request' (duration: 776.817345ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:37.95053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.173175Z","time spent":"777.277205ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:38.352097Z","caller":"traceutil/trace.go:171","msg":"trace[1236165263] linearizableReadLoop","detail":"{readStateIndex:26250; appliedIndex:26247; }","duration":"1.101000129s","start":"2026-09-22T02:41:37.251078Z","end":"2026-09-22T02:41:38.352079Z","steps":["trace[1236165263] 'read index received' (duration: 698.821005ms)","trace[1236165263] 'applied index is now lower than readState.Index' (duration: 402.178294ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:38.352241Z","caller":"traceutil/trace.go:171","msg":"trace[118962949] transaction","detail":"{read_only:false; response_revision:25416; number_of_response:1; }","duration":"1.029256367s","start":"2026-09-22T02:41:37.322972Z","end":"2026-09-22T02:41:38.352229Z","steps":["trace[118962949] 'process raft request' (duration: 988.781754ms)","trace[118962949] 'compare' (duration: 40.175456ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:38.352328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.322946Z","time spent":"1.029324089s","remote":"127.0.0.1:37034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":26,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:38.352595Z","caller":"traceutil/trace.go:171","msg":"trace[899358784] transaction","detail":"{read_only:false; response_revision:25417; number_of_response:1; }","duration":"564.023866ms","start":"2026-09-22T02:41:37.788556Z","end":"2026-09-22T02:41:38.35258Z","steps":["trace[899358784] 'process raft request' (duration: 563.464023ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:38.352693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.788533Z","time spent":"564.123518ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:38.352855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115216455s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:41:38.352893Z","caller":"traceutil/trace.go:171","msg":"trace[126496567] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:25417; }","duration":"1.115319007s","start":"2026-09-22T02:41:37.237565Z","end":"2026-09-22T02:41:38.352884Z","steps":["trace[126496567] 'agreement among raft nodes before linearized reading' (duration: 1.115245016s)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:38.352919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.237543Z","time spent":"1.115367989s","remote":"127.0.0.1:37508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":20,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:41:38.35306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183698504s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:38.353094Z","caller":"traceutil/trace.go:171","msg":"trace[779968279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25417; }","duration":"1.183731915s","start":"2026-09-22T02:41:37.169354Z","end":"2026-09-22T02:41:38.353086Z","steps":["trace[779968279] 'agreement among raft nodes before linearized reading' (duration: 1.183680364s)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:38.353116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.169316Z","time spent":"1.183794296s","remote":"127.0.0.1:37030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T02:41:38.35329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.966572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:41:38.353332Z","caller":"traceutil/trace.go:171","msg":"trace[447023619] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:25417; }","duration":"649.006073ms","start":"2026-09-22T02:41:37.704313Z","end":"2026-09-22T02:41:38.353319Z","steps":["trace[447023619] 'agreement among raft nodes before linearized reading' (duration: 648.945311ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:38.353359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.704261Z","time spent":"649.088474ms","remote":"127.0.0.1:54690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":31,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:41:38.353813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.552944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:41:38.353854Z","caller":"traceutil/trace.go:171","msg":"trace[672264297] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:25417; }","duration":"303.620925ms","start":"2026-09-22T02:41:38.050225Z","end":"2026-09-22T02:41:38.353846Z","steps":["trace[672264297] 'agreement among raft nodes before linearized reading' (duration: 303.529003ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:38.353879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:38.050208Z","time spent":"303.663687ms","remote":"127.0.0.1:37518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":60,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:41:38.354017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.997603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:38.354053Z","caller":"traceutil/trace.go:171","msg":"trace[1148881913] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25417; }","duration":"429.079365ms","start":"2026-09-22T02:41:37.924965Z","end":"2026-09-22T02:41:38.354044Z","steps":["trace[1148881913] 'agreement among raft nodes before linearized reading' (duration: 429.029184ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:38.354077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.924946Z","time spent":"429.123325ms","remote":"127.0.0.1:36330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:41:38.354225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.499657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:41:38.354263Z","caller":"traceutil/trace.go:171","msg":"trace[1171450542] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:25417; }","duration":"491.573349ms","start":"2026-09-22T02:41:37.862681Z","end":"2026-09-22T02:41:38.354254Z","steps":["trace[1171450542] 'agreement among raft nodes before linearized reading' (duration: 491.521228ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:38.354287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.862659Z","time spent":"491.620681ms","remote":"127.0.0.1:36218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:41:38.354481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.923504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-22T02:41:38.354515Z","caller":"traceutil/trace.go:171","msg":"trace[1353096614] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25417; }","duration":"507.996035ms","start":"2026-09-22T02:41:37.846511Z","end":"2026-09-22T02:41:38.354507Z","steps":["trace[1353096614] 'agreement among raft nodes before linearized reading' (duration: 507.890763ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:38.354537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:37.846491Z","time spent":"508.040226ms","remote":"127.0.0.1:37216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-22T02:41:39.098664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.095595ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:6"} {"level":"info","ts":"2026-09-22T02:41:39.098929Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21997} {"level":"info","ts":"2026-09-22T02:41:39.098989Z","caller":"traceutil/trace.go:171","msg":"trace[1238116193] transaction","detail":"{read_only:false; response_revision:25421; number_of_response:1; }","duration":"242.141043ms","start":"2026-09-22T02:41:38.856831Z","end":"2026-09-22T02:41:39.098973Z","steps":["trace[1238116193] 'process raft request' (duration: 242.106583ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:39.099235Z","caller":"traceutil/trace.go:171","msg":"trace[1284596919] compact","detail":"{revision:21997; response_revision:25421; }","duration":"739.220376ms","start":"2026-09-22T02:41:38.360004Z","end":"2026-09-22T02:41:39.099224Z","steps":["trace[1284596919] 'process raft request' (duration: 394.612266ms)","trace[1284596919] 'check and update compact revision' (duration: 342.901421ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:41:39.099267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:38.359974Z","time spent":"739.291337ms","remote":"127.0.0.1:37034","response type":"/etcdserverpb.KV/Compact","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-09-22T02:41:39.099403Z","caller":"traceutil/trace.go:171","msg":"trace[1443800771] linearizableReadLoop","detail":"{readStateIndex:26252; appliedIndex:26251; }","duration":"735.936283ms","start":"2026-09-22T02:41:38.363457Z","end":"2026-09-22T02:41:39.099394Z","steps":["trace[1443800771] 'read index received' (duration: 391.16732ms)","trace[1443800771] 'applied index is now lower than readState.Index' (duration: 344.768103ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:39.099481Z","caller":"traceutil/trace.go:171","msg":"trace[936970402] transaction","detail":"{read_only:false; response_revision:25420; number_of_response:1; }","duration":"699.803136ms","start":"2026-09-22T02:41:38.399669Z","end":"2026-09-22T02:41:39.099472Z","steps":["trace[936970402] 'process raft request' (duration: 699.237874ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:39.0995Z","caller":"traceutil/trace.go:171","msg":"trace[821241745] transaction","detail":"{read_only:false; response_revision:25419; number_of_response:1; }","duration":"717.197094ms","start":"2026-09-22T02:41:38.382257Z","end":"2026-09-22T02:41:39.099454Z","steps":["trace[821241745] 'process raft request' (duration: 716.605201ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:39.099675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:38.382244Z","time spent":"717.340727ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:39.099551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:38.39966Z","time spent":"699.841667ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:41:39.099785Z","caller":"traceutil/trace.go:171","msg":"trace[570534176] transaction","detail":"{read_only:false; response_revision:25418; number_of_response:1; }","duration":"718.328839ms","start":"2026-09-22T02:41:38.381446Z","end":"2026-09-22T02:41:39.099775Z","steps":["trace[570534176] 'process raft request' (duration: 717.305187ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:39.099828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:38.381429Z","time spent":"718.37708ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:41:39.099965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.803801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:41:39.099991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.571006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:41:39.100051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.928265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-22T02:41:39.10011Z","caller":"traceutil/trace.go:171","msg":"trace[135825] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25421; }","duration":"720.021377ms","start":"2026-09-22T02:41:38.380073Z","end":"2026-09-22T02:41:39.100094Z","steps":["trace[135825] 'agreement among raft nodes before linearized reading' (duration: 719.561087ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:39.100143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:38.380057Z","time spent":"720.077898ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-22T02:41:39.10007Z","caller":"traceutil/trace.go:171","msg":"trace[1010360258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25421; }","duration":"732.688849ms","start":"2026-09-22T02:41:38.367369Z","end":"2026-09-22T02:41:39.100057Z","steps":["trace[1010360258] 'agreement among raft nodes before linearized reading' (duration: 732.212069ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:39.100263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:38.367358Z","time spent":"732.863053ms","remote":"127.0.0.1:37026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T02:41:39.100017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.87019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:41:39.100368Z","caller":"traceutil/trace.go:171","msg":"trace[928322309] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25421; }","duration":"654.269059ms","start":"2026-09-22T02:41:38.44609Z","end":"2026-09-22T02:41:39.100359Z","steps":["trace[928322309] 'agreement among raft nodes before linearized reading' (duration: 653.534083ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:39.100396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:38.446068Z","time spent":"654.31902ms","remote":"127.0.0.1:50450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-09-22T02:41:39.100012Z","caller":"traceutil/trace.go:171","msg":"trace[1855113744] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25421; }","duration":"727.849682ms","start":"2026-09-22T02:41:38.372148Z","end":"2026-09-22T02:41:39.099998Z","steps":["trace[1855113744] 'agreement among raft nodes before linearized reading' (duration: 727.493044ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:39.100466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:38.372091Z","time spent":"728.371123ms","remote":"127.0.0.1:50386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-09-22T02:41:39.100826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.358084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-22T02:41:39.100884Z","caller":"traceutil/trace.go:171","msg":"trace[2135068515] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25421; }","duration":"737.470436ms","start":"2026-09-22T02:41:38.363395Z","end":"2026-09-22T02:41:39.100865Z","steps":["trace[2135068515] 'agreement among raft nodes before linearized reading' (duration: 736.145707ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:39.101032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:41:38.363371Z","time spent":"737.545088ms","remote":"127.0.0.1:37216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-22T02:41:39.270546Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21997,"took":"166.131486ms","hash":4027436152,"current-db-size-bytes":55689216,"current-db-size":"56 MB","current-db-size-in-use-bytes":45555712,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-09-22T02:41:39.270633Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4027436152,"revision":21997,"compact-revision":19777} {"level":"warn","ts":"2026-09-22T02:41:40.776072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.006618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4d7e9f40-80a7-479e-ba38-e626a76a0cf2\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-09-22T02:41:40.776171Z","caller":"traceutil/trace.go:171","msg":"trace[1897520026] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4d7e9f40-80a7-479e-ba38-e626a76a0cf2; range_end:; response_count:1; response_revision:25492; }","duration":"172.152612ms","start":"2026-09-22T02:41:40.603994Z","end":"2026-09-22T02:41:40.776146Z","steps":["trace[1897520026] 'range keys from in-memory index tree' (duration: 171.827525ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:46.105801Z","caller":"traceutil/trace.go:171","msg":"trace[134989781] linearizableReadLoop","detail":"{readStateIndex:26392; appliedIndex:26391; }","duration":"107.826396ms","start":"2026-09-22T02:41:45.997953Z","end":"2026-09-22T02:41:46.10578Z","steps":["trace[134989781] 'read index received' (duration: 107.621521ms)","trace[134989781] 'applied index is now lower than readState.Index' (duration: 203.935µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:46.105912Z","caller":"traceutil/trace.go:171","msg":"trace[1978994015] transaction","detail":"{read_only:false; response_revision:25554; number_of_response:1; }","duration":"113.150484ms","start":"2026-09-22T02:41:45.992749Z","end":"2026-09-22T02:41:46.1059Z","steps":["trace[1978994015] 'process raft request' (duration: 112.900079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:46.106115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.143933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-22T02:41:46.106183Z","caller":"traceutil/trace.go:171","msg":"trace[732447831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25554; }","duration":"108.238354ms","start":"2026-09-22T02:41:45.997927Z","end":"2026-09-22T02:41:46.106166Z","steps":["trace[732447831] 'agreement among raft nodes before linearized reading' (duration: 108.06242ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:41:46.286024Z","caller":"traceutil/trace.go:171","msg":"trace[1371572523] transaction","detail":"{read_only:false; response_revision:25555; number_of_response:1; }","duration":"176.54315ms","start":"2026-09-22T02:41:46.109443Z","end":"2026-09-22T02:41:46.285986Z","steps":["trace[1371572523] 'process raft request' (duration: 134.199625ms)","trace[1371572523] 'compare' (duration: 42.203302ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:46.379781Z","caller":"traceutil/trace.go:171","msg":"trace[1100327197] linearizableReadLoop","detail":"{readStateIndex:26394; appliedIndex:26392; }","duration":"247.904893ms","start":"2026-09-22T02:41:46.13185Z","end":"2026-09-22T02:41:46.379755Z","steps":["trace[1100327197] 'read index received' (duration: 111.775255ms)","trace[1100327197] 'applied index is now lower than readState.Index' (duration: 136.128358ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:41:46.379843Z","caller":"traceutil/trace.go:171","msg":"trace[1395538030] transaction","detail":"{read_only:false; response_revision:25556; number_of_response:1; }","duration":"261.733341ms","start":"2026-09-22T02:41:46.118073Z","end":"2026-09-22T02:41:46.379806Z","steps":["trace[1395538030] 'process raft request' (duration: 261.491965ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:41:46.379983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.129597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-09-22T02:41:46.380025Z","caller":"traceutil/trace.go:171","msg":"trace[1451455123] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25556; }","duration":"248.182348ms","start":"2026-09-22T02:41:46.131829Z","end":"2026-09-22T02:41:46.380011Z","steps":["trace[1451455123] 'agreement among raft nodes before linearized reading' (duration: 248.045415ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:03.257498Z","caller":"traceutil/trace.go:171","msg":"trace[1412742928] transaction","detail":"{read_only:false; response_revision:25691; number_of_response:1; }","duration":"114.850703ms","start":"2026-09-22T02:42:03.142619Z","end":"2026-09-22T02:42:03.25747Z","steps":["trace[1412742928] 'process raft request' (duration: 114.68042ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:03.396863Z","caller":"traceutil/trace.go:171","msg":"trace[1997712730] transaction","detail":"{read_only:false; response_revision:25692; number_of_response:1; }","duration":"206.306243ms","start":"2026-09-22T02:42:03.190528Z","end":"2026-09-22T02:42:03.396835Z","steps":["trace[1997712730] 'process raft request' (duration: 205.999896ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:03.397039Z","caller":"traceutil/trace.go:171","msg":"trace[90399994] transaction","detail":"{read_only:false; response_revision:25693; number_of_response:1; }","duration":"203.185513ms","start":"2026-09-22T02:42:03.193819Z","end":"2026-09-22T02:42:03.397005Z","steps":["trace[90399994] 'process raft request' (duration: 202.900937ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:03.398248Z","caller":"traceutil/trace.go:171","msg":"trace[738905315] transaction","detail":"{read_only:false; response_revision:25694; number_of_response:1; }","duration":"203.008441ms","start":"2026-09-22T02:42:03.195215Z","end":"2026-09-22T02:42:03.398224Z","steps":["trace[738905315] 'process raft request' (duration: 201.700411ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:03.39883Z","caller":"traceutil/trace.go:171","msg":"trace[1482496869] transaction","detail":"{read_only:false; response_revision:25695; number_of_response:1; }","duration":"142.285255ms","start":"2026-09-22T02:42:03.256523Z","end":"2026-09-22T02:42:03.398808Z","steps":["trace[1482496869] 'process raft request' (duration: 141.16738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:03.399504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.974077ms","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-09-22T02:42:03.399602Z","caller":"traceutil/trace.go:171","msg":"trace[1052210698] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25697; }","duration":"154.094288ms","start":"2026-09-22T02:42:03.24546Z","end":"2026-09-22T02:42:03.399554Z","steps":["trace[1052210698] 'agreement among raft nodes before linearized reading' (duration: 153.911594ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:03.398782Z","caller":"traceutil/trace.go:171","msg":"trace[989666868] linearizableReadLoop","detail":"{readStateIndex:26536; appliedIndex:26532; }","duration":"153.24401ms","start":"2026-09-22T02:42:03.245518Z","end":"2026-09-22T02:42:03.398762Z","steps":["trace[989666868] 'read index received' (duration: 11.660991ms)","trace[989666868] 'applied index is now lower than readState.Index' (duration: 141.581579ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:03.657041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.450103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:42:03.657149Z","caller":"traceutil/trace.go:171","msg":"trace[1148669944] linearizableReadLoop","detail":"{readStateIndex:26541; appliedIndex:26539; }","duration":"246.91619ms","start":"2026-09-22T02:42:03.410216Z","end":"2026-09-22T02:42:03.657133Z","steps":["trace[1148669944] 'read index received' (duration: 22.193786ms)","trace[1148669944] 'applied index is now lower than readState.Index' (duration: 224.721444ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:42:03.657176Z","caller":"traceutil/trace.go:171","msg":"trace[1442141923] transaction","detail":"{read_only:false; response_revision:25699; number_of_response:1; }","duration":"252.910274ms","start":"2026-09-22T02:42:03.404242Z","end":"2026-09-22T02:42:03.657152Z","steps":["trace[1442141923] 'process raft request' (duration: 134.271926ms)","trace[1442141923] 'compare' (duration: 118.324031ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:03.657367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.145765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-22T02:42:03.657409Z","caller":"traceutil/trace.go:171","msg":"trace[1814011975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25699; }","duration":"247.203916ms","start":"2026-09-22T02:42:03.41019Z","end":"2026-09-22T02:42:03.657394Z","steps":["trace[1814011975] 'agreement among raft nodes before linearized reading' (duration: 247.074413ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:03.657432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.889161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-22T02:42:03.657518Z","caller":"traceutil/trace.go:171","msg":"trace[1076057708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25699; }","duration":"235.012253ms","start":"2026-09-22T02:42:03.422485Z","end":"2026-09-22T02:42:03.657498Z","steps":["trace[1076057708] 'agreement among raft nodes before linearized reading' (duration: 234.775738ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:03.848708Z","caller":"traceutil/trace.go:171","msg":"trace[1449982830] transaction","detail":"{read_only:false; response_revision:25700; number_of_response:1; }","duration":"186.235007ms","start":"2026-09-22T02:42:03.662437Z","end":"2026-09-22T02:42:03.848672Z","steps":["trace[1449982830] 'process raft request' (duration: 127.429984ms)","trace[1449982830] 'compare' (duration: 58.661029ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:42:03.98332Z","caller":"traceutil/trace.go:171","msg":"trace[430751276] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"320.587764ms","start":"2026-09-22T02:42:03.662681Z","end":"2026-09-22T02:42:03.983269Z","steps":["trace[430751276] 'process raft request' (duration: 288.566939ms)","trace[430751276] 'compare' (duration: 31.80442ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:03.98355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:03.662668Z","time spent":"320.754437ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:42:04.244793Z","caller":"traceutil/trace.go:171","msg":"trace[1007971735] transaction","detail":"{read_only:false; response_revision:25702; number_of_response:1; }","duration":"108.567843ms","start":"2026-09-22T02:42:04.136192Z","end":"2026-09-22T02:42:04.244759Z","steps":["trace[1007971735] 'process raft request' (duration: 107.490039ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:04.429821Z","caller":"traceutil/trace.go:171","msg":"trace[1761674512] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"179.343251ms","start":"2026-09-22T02:42:04.250437Z","end":"2026-09-22T02:42:04.429781Z","steps":["trace[1761674512] 'process raft request' (duration: 113.064232ms)","trace[1761674512] 'compare' (duration: 65.41105ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:04.829433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.871867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:42:04.829585Z","caller":"traceutil/trace.go:171","msg":"trace[1458323273] linearizableReadLoop","detail":"{readStateIndex:26547; appliedIndex:26546; }","duration":"120.267844ms","start":"2026-09-22T02:42:04.709267Z","end":"2026-09-22T02:42:04.829535Z","steps":["trace[1458323273] 'read index received' (duration: 61.661µs)","trace[1458323273] 'applied index is now lower than readState.Index' (duration: 120.204243ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:42:04.82965Z","caller":"traceutil/trace.go:171","msg":"trace[1917525547] transaction","detail":"{read_only:false; response_revision:25705; number_of_response:1; }","duration":"316.853741ms","start":"2026-09-22T02:42:04.512769Z","end":"2026-09-22T02:42:04.829623Z","steps":["trace[1917525547] 'process raft request' (duration: 126.687147ms)","trace[1917525547] 'compare' (duration: 189.710004ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:04.829775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:04.512742Z","time spent":"316.963433ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:42:04.829939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.630081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:42:04.830013Z","caller":"traceutil/trace.go:171","msg":"trace[1721643908] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:25705; }","duration":"120.751175ms","start":"2026-09-22T02:42:04.709244Z","end":"2026-09-22T02:42:04.829995Z","steps":["trace[1721643908] 'agreement among raft nodes before linearized reading' (duration: 120.376727ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:05.167104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.403464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:42:05.167224Z","caller":"traceutil/trace.go:171","msg":"trace[201536185] linearizableReadLoop","detail":"{readStateIndex:26548; appliedIndex:26547; }","duration":"292.67326ms","start":"2026-09-22T02:42:04.874531Z","end":"2026-09-22T02:42:05.167204Z","steps":["trace[201536185] 'read index received' (duration: 150.946708ms)","trace[201536185] 'applied index is now lower than readState.Index' (duration: 141.725202ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:42:05.167297Z","caller":"traceutil/trace.go:171","msg":"trace[1178319528] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"293.754734ms","start":"2026-09-22T02:42:04.873531Z","end":"2026-09-22T02:42:05.167286Z","steps":["trace[1178319528] 'process raft request' (duration: 152.064493ms)","trace[1178319528] 'compare' (duration: 141.254081ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:05.167601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.015548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-09-22T02:42:05.167706Z","caller":"traceutil/trace.go:171","msg":"trace[1633853965] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25706; }","duration":"293.165393ms","start":"2026-09-22T02:42:04.874512Z","end":"2026-09-22T02:42:05.167677Z","steps":["trace[1633853965] 'agreement among raft nodes before linearized reading' (duration: 292.819944ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:05.304103Z","caller":"traceutil/trace.go:171","msg":"trace[2029485082] linearizableReadLoop","detail":"{readStateIndex:26549; appliedIndex:26548; }","duration":"136.830853ms","start":"2026-09-22T02:42:05.16725Z","end":"2026-09-22T02:42:05.304081Z","steps":["trace[2029485082] 'read index received' (duration: 102.171021ms)","trace[2029485082] 'applied index is now lower than readState.Index' (duration: 34.658882ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:42:05.304236Z","caller":"traceutil/trace.go:171","msg":"trace[8131640] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"161.78828ms","start":"2026-09-22T02:42:05.14241Z","end":"2026-09-22T02:42:05.304198Z","steps":["trace[8131640] 'process raft request' (duration: 126.998925ms)","trace[8131640] 'compare' (duration: 34.5665ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:05.304286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.742739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:42:05.304369Z","caller":"traceutil/trace.go:171","msg":"trace[632910232] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25707; }","duration":"403.890462ms","start":"2026-09-22T02:42:04.900459Z","end":"2026-09-22T02:42:05.304349Z","steps":["trace[632910232] 'agreement among raft nodes before linearized reading' (duration: 403.736359ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:05.30443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:04.900439Z","time spent":"403.982865ms","remote":"127.0.0.1:37482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-09-22T02:42:05.307417Z","caller":"traceutil/trace.go:171","msg":"trace[1563288438] transaction","detail":"{read_only:false; response_revision:25708; number_of_response:1; }","duration":"132.441945ms","start":"2026-09-22T02:42:05.17495Z","end":"2026-09-22T02:42:05.307391Z","steps":["trace[1563288438] 'process raft request' (duration: 130.991583ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:05.307778Z","caller":"traceutil/trace.go:171","msg":"trace[1777558572] transaction","detail":"{read_only:false; response_revision:25709; number_of_response:1; }","duration":"131.909354ms","start":"2026-09-22T02:42:05.175844Z","end":"2026-09-22T02:42:05.307754Z","steps":["trace[1777558572] 'process raft request' (duration: 131.457443ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:05.30792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.594526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-4d7e9f40-80a7-479e-ba38-e626a76a0cf2\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-09-22T02:42:05.307978Z","caller":"traceutil/trace.go:171","msg":"trace[1094289196] range","detail":"{range_begin:/registry/persistentvolumes/pvc-4d7e9f40-80a7-479e-ba38-e626a76a0cf2; range_end:; response_count:1; response_revision:25711; }","duration":"135.676137ms","start":"2026-09-22T02:42:05.172273Z","end":"2026-09-22T02:42:05.307949Z","steps":["trace[1094289196] 'agreement among raft nodes before linearized reading' (duration: 135.487933ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:05.308028Z","caller":"traceutil/trace.go:171","msg":"trace[519821061] transaction","detail":"{read_only:false; response_revision:25710; number_of_response:1; }","duration":"132.141609ms","start":"2026-09-22T02:42:05.175874Z","end":"2026-09-22T02:42:05.308016Z","steps":["trace[519821061] 'process raft request' (duration: 131.724219ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:05.308186Z","caller":"traceutil/trace.go:171","msg":"trace[674003990] transaction","detail":"{read_only:false; response_revision:25711; number_of_response:1; }","duration":"120.260723ms","start":"2026-09-22T02:42:05.187901Z","end":"2026-09-22T02:42:05.308161Z","steps":["trace[674003990] 'process raft request' (duration: 119.804172ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:10.936699Z","caller":"traceutil/trace.go:171","msg":"trace[1084094629] transaction","detail":"{read_only:false; response_revision:25758; number_of_response:1; }","duration":"144.083015ms","start":"2026-09-22T02:42:10.79258Z","end":"2026-09-22T02:42:10.936663Z","steps":["trace[1084094629] 'process raft request' (duration: 138.890499ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:11.015871Z","caller":"traceutil/trace.go:171","msg":"trace[592057011] transaction","detail":"{read_only:false; response_revision:25759; number_of_response:1; }","duration":"162.086527ms","start":"2026-09-22T02:42:10.853745Z","end":"2026-09-22T02:42:11.015832Z","steps":["trace[592057011] 'process raft request' (duration: 161.585346ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:11.150522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.979282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:42:11.150641Z","caller":"traceutil/trace.go:171","msg":"trace[1440054846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25759; }","duration":"105.148905ms","start":"2026-09-22T02:42:11.045469Z","end":"2026-09-22T02:42:11.150618Z","steps":["trace[1440054846] 'range keys from in-memory index tree' (duration: 104.856419ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:11.562845Z","caller":"traceutil/trace.go:171","msg":"trace[631709308] transaction","detail":"{read_only:false; response_revision:25760; number_of_response:1; }","duration":"140.191628ms","start":"2026-09-22T02:42:11.422628Z","end":"2026-09-22T02:42:11.56282Z","steps":["trace[631709308] 'process raft request' (duration: 140.029625ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:11.641634Z","caller":"traceutil/trace.go:171","msg":"trace[1382100857] transaction","detail":"{read_only:false; response_revision:25761; number_of_response:1; }","duration":"215.900338ms","start":"2026-09-22T02:42:11.425706Z","end":"2026-09-22T02:42:11.641607Z","steps":["trace[1382100857] 'process raft request' (duration: 215.670153ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:11.641939Z","caller":"traceutil/trace.go:171","msg":"trace[206074199] transaction","detail":"{read_only:false; response_revision:25762; number_of_response:1; }","duration":"214.026867ms","start":"2026-09-22T02:42:11.427873Z","end":"2026-09-22T02:42:11.6419Z","steps":["trace[206074199] 'process raft request' (duration: 213.665108ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:11.641962Z","caller":"traceutil/trace.go:171","msg":"trace[1348697829] transaction","detail":"{read_only:false; response_revision:25763; number_of_response:1; }","duration":"211.819856ms","start":"2026-09-22T02:42:11.430114Z","end":"2026-09-22T02:42:11.641934Z","steps":["trace[1348697829] 'process raft request' (duration: 211.707464ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:11.642127Z","caller":"traceutil/trace.go:171","msg":"trace[2048092953] linearizableReadLoop","detail":"{readStateIndex:26609; appliedIndex:26603; }","duration":"160.661546ms","start":"2026-09-22T02:42:11.481446Z","end":"2026-09-22T02:42:11.642108Z","steps":["trace[2048092953] 'read index received' (duration: 81.178082ms)","trace[2048092953] 'applied index is now lower than readState.Index' (duration: 79.482764ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:42:11.642155Z","caller":"traceutil/trace.go:171","msg":"trace[1980229287] transaction","detail":"{read_only:false; response_revision:25764; number_of_response:1; }","duration":"211.874058ms","start":"2026-09-22T02:42:11.430264Z","end":"2026-09-22T02:42:11.642138Z","steps":["trace[1980229287] 'process raft request' (duration: 211.630162ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:11.642199Z","caller":"traceutil/trace.go:171","msg":"trace[1974001240] transaction","detail":"{read_only:false; response_revision:25765; number_of_response:1; }","duration":"206.056679ms","start":"2026-09-22T02:42:11.436129Z","end":"2026-09-22T02:42:11.642186Z","steps":["trace[1974001240] 'process raft request' (duration: 205.919406ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:11.642328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.876361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-22T02:42:11.642364Z","caller":"traceutil/trace.go:171","msg":"trace[917995627] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25765; }","duration":"160.972563ms","start":"2026-09-22T02:42:11.48138Z","end":"2026-09-22T02:42:11.642353Z","steps":["trace[917995627] 'agreement among raft nodes before linearized reading' (duration: 160.84702ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:11.766453Z","caller":"traceutil/trace.go:171","msg":"trace[675766094] transaction","detail":"{read_only:false; response_revision:25766; number_of_response:1; }","duration":"118.734669ms","start":"2026-09-22T02:42:11.647686Z","end":"2026-09-22T02:42:11.766421Z","steps":["trace[675766094] 'process raft request' (duration: 82.238964ms)","trace[675766094] 'compare' (duration: 36.29262ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:42:16.125605Z","caller":"traceutil/trace.go:171","msg":"trace[1483421445] transaction","detail":"{read_only:false; response_revision:25799; number_of_response:1; }","duration":"113.141784ms","start":"2026-09-22T02:42:16.012417Z","end":"2026-09-22T02:42:16.125559Z","steps":["trace[1483421445] 'process raft request' (duration: 112.890969ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:16.273859Z","caller":"traceutil/trace.go:171","msg":"trace[849822444] linearizableReadLoop","detail":"{readStateIndex:26645; appliedIndex:26644; }","duration":"105.478534ms","start":"2026-09-22T02:42:16.168362Z","end":"2026-09-22T02:42:16.27384Z","steps":["trace[849822444] 'read index received' (duration: 19.978547ms)","trace[849822444] 'applied index is now lower than readState.Index' (duration: 85.499397ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:16.273965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.580956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:42:16.273982Z","caller":"traceutil/trace.go:171","msg":"trace[2011214571] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:25800; }","duration":"105.651128ms","start":"2026-09-22T02:42:16.168325Z","end":"2026-09-22T02:42:16.273977Z","steps":["trace[2011214571] 'agreement among raft nodes before linearized reading' (duration: 105.582976ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:16.274081Z","caller":"traceutil/trace.go:171","msg":"trace[1916068857] transaction","detail":"{read_only:false; response_revision:25800; number_of_response:1; }","duration":"144.013303ms","start":"2026-09-22T02:42:16.130027Z","end":"2026-09-22T02:42:16.27404Z","steps":["trace[1916068857] 'process raft request' (duration: 58.407044ms)","trace[1916068857] 'compare' (duration: 85.278322ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:42:17.698063Z","caller":"traceutil/trace.go:171","msg":"trace[1175386855] transaction","detail":"{read_only:false; response_revision:25804; number_of_response:1; }","duration":"113.579334ms","start":"2026-09-22T02:42:17.584461Z","end":"2026-09-22T02:42:17.69804Z","steps":["trace[1175386855] 'process raft request' (duration: 113.431551ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:17.780526Z","caller":"traceutil/trace.go:171","msg":"trace[1621924220] transaction","detail":"{read_only:false; response_revision:25805; number_of_response:1; }","duration":"121.772377ms","start":"2026-09-22T02:42:17.658728Z","end":"2026-09-22T02:42:17.7805Z","steps":["trace[1621924220] 'process raft request' (duration: 121.585003ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:17.780893Z","caller":"traceutil/trace.go:171","msg":"trace[2041199116] transaction","detail":"{read_only:false; response_revision:25806; number_of_response:1; }","duration":"116.327026ms","start":"2026-09-22T02:42:17.664527Z","end":"2026-09-22T02:42:17.780854Z","steps":["trace[2041199116] 'process raft request' (duration: 115.929827ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:17.781077Z","caller":"traceutil/trace.go:171","msg":"trace[1470778271] transaction","detail":"{read_only:false; response_revision:25807; number_of_response:1; }","duration":"113.362909ms","start":"2026-09-22T02:42:17.667689Z","end":"2026-09-22T02:42:17.781052Z","steps":["trace[1470778271] 'process raft request' (duration: 113.188566ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:17.78132Z","caller":"traceutil/trace.go:171","msg":"trace[1859975925] transaction","detail":"{read_only:false; response_revision:25809; number_of_response:1; }","duration":"111.916028ms","start":"2026-09-22T02:42:17.669381Z","end":"2026-09-22T02:42:17.781297Z","steps":["trace[1859975925] 'process raft request' (duration: 111.786795ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:17.781329Z","caller":"traceutil/trace.go:171","msg":"trace[741490565] transaction","detail":"{read_only:false; response_revision:25808; number_of_response:1; }","duration":"112.967191ms","start":"2026-09-22T02:42:17.668333Z","end":"2026-09-22T02:42:17.7813Z","steps":["trace[741490565] 'process raft request' (duration: 112.671674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:17.979259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.999975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:42:17.97937Z","caller":"traceutil/trace.go:171","msg":"trace[1309050233] linearizableReadLoop","detail":"{readStateIndex:26655; appliedIndex:26654; }","duration":"183.963776ms","start":"2026-09-22T02:42:17.795389Z","end":"2026-09-22T02:42:17.979353Z","steps":["trace[1309050233] 'read index received' (duration: 34.669944ms)","trace[1309050233] 'applied index is now lower than readState.Index' (duration: 149.292352ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:17.979568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.607888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:42:17.979572Z","caller":"traceutil/trace.go:171","msg":"trace[708060051] transaction","detail":"{read_only:false; response_revision:25810; number_of_response:1; }","duration":"273.33562ms","start":"2026-09-22T02:42:17.706116Z","end":"2026-09-22T02:42:17.979513Z","steps":["trace[708060051] 'process raft request' (duration: 123.981198ms)","trace[708060051] 'compare' (duration: 148.740849ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:17.97961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.737724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-09-22T02:42:17.97961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.956764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:42:17.979654Z","caller":"traceutil/trace.go:171","msg":"trace[485175101] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25810; }","duration":"124.802485ms","start":"2026-09-22T02:42:17.85484Z","end":"2026-09-22T02:42:17.979643Z","steps":["trace[485175101] 'agreement among raft nodes before linearized reading' (duration: 124.629791ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:17.979649Z","caller":"traceutil/trace.go:171","msg":"trace[1682465203] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25810; }","duration":"166.823704ms","start":"2026-09-22T02:42:17.812803Z","end":"2026-09-22T02:42:17.979627Z","steps":["trace[1682465203] 'agreement among raft nodes before linearized reading' (duration: 166.6703ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:17.979685Z","caller":"traceutil/trace.go:171","msg":"trace[1047052475] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25810; }","duration":"149.067867ms","start":"2026-09-22T02:42:17.830596Z","end":"2026-09-22T02:42:17.979664Z","steps":["trace[1047052475] 'agreement among raft nodes before linearized reading' (duration: 148.902913ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:17.979903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.498057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-22T02:42:17.9808Z","caller":"traceutil/trace.go:171","msg":"trace[1319008992] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25810; }","duration":"185.441288ms","start":"2026-09-22T02:42:17.795328Z","end":"2026-09-22T02:42:17.98077Z","steps":["trace[1319008992] 'agreement among raft nodes before linearized reading' (duration: 184.110709ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:18.213025Z","caller":"traceutil/trace.go:171","msg":"trace[1601982467] linearizableReadLoop","detail":"{readStateIndex:26657; appliedIndex:26656; }","duration":"223.828945ms","start":"2026-09-22T02:42:17.989156Z","end":"2026-09-22T02:42:18.212985Z","steps":["trace[1601982467] 'read index received' (duration: 29.57994ms)","trace[1601982467] 'applied index is now lower than readState.Index' (duration: 194.247494ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:42:18.213188Z","caller":"traceutil/trace.go:171","msg":"trace[565318556] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"227.23634ms","start":"2026-09-22T02:42:17.985867Z","end":"2026-09-22T02:42:18.213104Z","steps":["trace[565318556] 'process raft request' (duration: 142.089511ms)","trace[565318556] 'compare' (duration: 84.71053ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:18.21332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.127151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-22T02:42:18.213362Z","caller":"traceutil/trace.go:171","msg":"trace[132103731] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:25812; }","duration":"224.194292ms","start":"2026-09-22T02:42:17.989151Z","end":"2026-09-22T02:42:18.213345Z","steps":["trace[132103731] 'agreement among raft nodes before linearized reading' (duration: 223.972498ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:18.213462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.720301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:419219"} {"level":"info","ts":"2026-09-22T02:42:18.213529Z","caller":"traceutil/trace.go:171","msg":"trace[1156343569] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25812; }","duration":"167.842844ms","start":"2026-09-22T02:42:18.045669Z","end":"2026-09-22T02:42:18.213512Z","steps":["trace[1156343569] 'agreement among raft nodes before linearized reading' (duration: 167.507027ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:18.85084Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006827.wal"} {"level":"info","ts":"2026-09-22T02:42:18.851906Z","caller":"traceutil/trace.go:171","msg":"trace[1153464618] transaction","detail":"{read_only:false; response_revision:25817; number_of_response:1; }","duration":"107.08957ms","start":"2026-09-22T02:42:18.744783Z","end":"2026-09-22T02:42:18.851872Z","steps":["trace[1153464618] 'process raft request' (duration: 106.62654ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:19.749412Z","caller":"traceutil/trace.go:171","msg":"trace[1806058963] linearizableReadLoop","detail":"{readStateIndex:26664; appliedIndex:26663; }","duration":"382.658609ms","start":"2026-09-22T02:42:19.366728Z","end":"2026-09-22T02:42:19.749387Z","steps":["trace[1806058963] 'read index received' (duration: 382.570597ms)","trace[1806058963] 'applied index is now lower than readState.Index' (duration: 86.762µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:19.749568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.804282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:42:19.749553Z","caller":"traceutil/trace.go:171","msg":"trace[1433778061] transaction","detail":"{read_only:false; response_revision:25819; number_of_response:1; }","duration":"484.705795ms","start":"2026-09-22T02:42:19.264793Z","end":"2026-09-22T02:42:19.749499Z","steps":["trace[1433778061] 'process raft request' (duration: 484.438909ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:19.749599Z","caller":"traceutil/trace.go:171","msg":"trace[2025546444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25819; }","duration":"382.911654ms","start":"2026-09-22T02:42:19.366678Z","end":"2026-09-22T02:42:19.74959Z","steps":["trace[2025546444] 'agreement among raft nodes before linearized reading' (duration: 382.822922ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:19.74963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:19.366654Z","time spent":"382.967456ms","remote":"127.0.0.1:37026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T02:42:19.749697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:19.264759Z","time spent":"484.858059ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:42:20.02395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.642995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:42:20.024115Z","caller":"traceutil/trace.go:171","msg":"trace[1755583883] linearizableReadLoop","detail":"{readStateIndex:26665; appliedIndex:26664; }","duration":"274.614368ms","start":"2026-09-22T02:42:19.749474Z","end":"2026-09-22T02:42:20.024088Z","steps":["trace[1755583883] 'read index received' (duration: 135.651426ms)","trace[1755583883] 'applied index is now lower than readState.Index' (duration: 138.960682ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:42:20.024252Z","caller":"traceutil/trace.go:171","msg":"trace[1050582359] transaction","detail":"{read_only:false; response_revision:25820; number_of_response:1; }","duration":"321.061045ms","start":"2026-09-22T02:42:19.703176Z","end":"2026-09-22T02:42:20.024237Z","steps":["trace[1050582359] 'process raft request' (duration: 181.956501ms)","trace[1050582359] 'compare' (duration: 138.220415ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:20.024353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:19.703149Z","time spent":"321.141506ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:42:20.024502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.679646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager\" ","response":"range_response_count:1 size:969"} {"level":"info","ts":"2026-09-22T02:42:20.024607Z","caller":"traceutil/trace.go:171","msg":"trace[735065886] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager; range_end:; response_count:1; response_revision:25820; }","duration":"497.83831ms","start":"2026-09-22T02:42:19.526748Z","end":"2026-09-22T02:42:20.024586Z","steps":["trace[735065886] 'agreement among raft nodes before linearized reading' (duration: 497.662556ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:20.024593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.211986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:42:20.024628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.479084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-22T02:42:20.02464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.12926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19027"} {"level":"info","ts":"2026-09-22T02:42:20.024675Z","caller":"traceutil/trace.go:171","msg":"trace[464820885] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25820; }","duration":"441.337789ms","start":"2026-09-22T02:42:19.583315Z","end":"2026-09-22T02:42:20.024653Z","steps":["trace[464820885] 'agreement among raft nodes before linearized reading' (duration: 441.175006ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:20.024713Z","caller":"traceutil/trace.go:171","msg":"trace[1373910575] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:25820; }","duration":"572.602957ms","start":"2026-09-22T02:42:19.452089Z","end":"2026-09-22T02:42:20.024692Z","steps":["trace[1373910575] 'agreement among raft nodes before linearized reading' (duration: 572.328871ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:20.024722Z","caller":"traceutil/trace.go:171","msg":"trace[1758137173] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25820; }","duration":"621.270853ms","start":"2026-09-22T02:42:19.403426Z","end":"2026-09-22T02:42:20.024697Z","steps":["trace[1758137173] 'agreement among raft nodes before linearized reading' (duration: 621.030288ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:20.024724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:19.583298Z","time spent":"441.41431ms","remote":"127.0.0.1:52724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:42:20.024761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:19.403405Z","time spent":"621.347985ms","remote":"127.0.0.1:37200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19050,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-09-22T02:42:20.02476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:19.452072Z","time spent":"572.678998ms","remote":"127.0.0.1:37228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":92,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:42:20.024653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:19.526728Z","time spent":"497.916201ms","remote":"127.0.0.1:37242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":992,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager\" "} {"level":"info","ts":"2026-09-22T02:42:20.12066Z","caller":"traceutil/trace.go:171","msg":"trace[1744911958] transaction","detail":"{read_only:false; response_revision:25821; number_of_response:1; }","duration":"333.693096ms","start":"2026-09-22T02:42:19.786945Z","end":"2026-09-22T02:42:20.120638Z","steps":["trace[1744911958] 'process raft request' (duration: 333.510832ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:20.120815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:19.786916Z","time spent":"333.817059ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:42:20.121135Z","caller":"traceutil/trace.go:171","msg":"trace[1419388217] transaction","detail":"{read_only:false; response_revision:25822; number_of_response:1; }","duration":"334.064894ms","start":"2026-09-22T02:42:19.787009Z","end":"2026-09-22T02:42:20.121074Z","steps":["trace[1419388217] 'process raft request' (duration: 333.590284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:20.121455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:19.786985Z","time spent":"334.3197ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:42:20.121685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.909038ms","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:6"} {"level":"info","ts":"2026-09-22T02:42:20.121735Z","caller":"traceutil/trace.go:171","msg":"trace[1545902284] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:25825; }","duration":"267.988781ms","start":"2026-09-22T02:42:19.853731Z","end":"2026-09-22T02:42:20.12172Z","steps":["trace[1545902284] 'agreement among raft nodes before linearized reading' (duration: 267.906899ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:20.121804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.970211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:42:20.121888Z","caller":"traceutil/trace.go:171","msg":"trace[1480700725] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:25825; }","duration":"320.095704ms","start":"2026-09-22T02:42:19.801765Z","end":"2026-09-22T02:42:20.121861Z","steps":["trace[1480700725] 'agreement among raft nodes before linearized reading' (duration: 319.884279ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:20.121898Z","caller":"traceutil/trace.go:171","msg":"trace[33668212] transaction","detail":"{read_only:false; response_revision:25823; number_of_response:1; }","duration":"334.842532ms","start":"2026-09-22T02:42:19.787043Z","end":"2026-09-22T02:42:20.121885Z","steps":["trace[33668212] 'process raft request' (duration: 333.968802ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:20.121935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:19.801739Z","time spent":"320.185576ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":98,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:42:20.121953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:19.787016Z","time spent":"334.908254ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:42:20.122052Z","caller":"traceutil/trace.go:171","msg":"trace[716825014] transaction","detail":"{read_only:false; response_revision:25824; number_of_response:1; }","duration":"334.851823ms","start":"2026-09-22T02:42:19.787192Z","end":"2026-09-22T02:42:20.122043Z","steps":["trace[716825014] 'process raft request' (duration: 334.093485ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:20.122096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:19.787181Z","time spent":"334.889753ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:42:20.122211Z","caller":"traceutil/trace.go:171","msg":"trace[671593713] transaction","detail":"{read_only:false; response_revision:25825; number_of_response:1; }","duration":"334.974946ms","start":"2026-09-22T02:42:19.7872Z","end":"2026-09-22T02:42:20.122175Z","steps":["trace[671593713] 'process raft request' (duration: 334.134486ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:20.122408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:19.787181Z","time spent":"335.107778ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:42:20.122523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.772486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:42:20.122633Z","caller":"traceutil/trace.go:171","msg":"trace[959650815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25825; }","duration":"371.93951ms","start":"2026-09-22T02:42:19.750673Z","end":"2026-09-22T02:42:20.122612Z","steps":["trace[959650815] 'agreement among raft nodes before linearized reading' (duration: 371.728526ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:20.122683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:19.750652Z","time spent":"372.022622ms","remote":"127.0.0.1:37030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-22T02:42:20.375467Z","caller":"traceutil/trace.go:171","msg":"trace[608628612] transaction","detail":"{read_only:false; response_revision:25827; number_of_response:1; }","duration":"147.92361ms","start":"2026-09-22T02:42:20.227519Z","end":"2026-09-22T02:42:20.375443Z","steps":["trace[608628612] 'process raft request' (duration: 129.09309ms)","trace[608628612] 'compare' (duration: 18.704838ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:42:20.462231Z","caller":"traceutil/trace.go:171","msg":"trace[1125204700] linearizableReadLoop","detail":"{readStateIndex:26673; appliedIndex:26671; }","duration":"167.667792ms","start":"2026-09-22T02:42:20.294544Z","end":"2026-09-22T02:42:20.462212Z","steps":["trace[1125204700] 'read index received' (duration: 62.087775ms)","trace[1125204700] 'applied index is now lower than readState.Index' (duration: 105.578667ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:20.46242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.053003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T02:42:20.462446Z","caller":"traceutil/trace.go:171","msg":"trace[517505400] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:25829; }","duration":"161.201337ms","start":"2026-09-22T02:42:20.301238Z","end":"2026-09-22T02:42:20.462439Z","steps":["trace[517505400] 'agreement among raft nodes before linearized reading' (duration: 161.094744ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:20.462439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.870236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:42:20.462523Z","caller":"traceutil/trace.go:171","msg":"trace[1920006749] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:25829; }","duration":"168.010169ms","start":"2026-09-22T02:42:20.294493Z","end":"2026-09-22T02:42:20.462503Z","steps":["trace[1920006749] 'agreement among raft nodes before linearized reading' (duration: 167.811485ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:20.462657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.695325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-22T02:42:20.462741Z","caller":"traceutil/trace.go:171","msg":"trace[307293444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25829; }","duration":"125.797117ms","start":"2026-09-22T02:42:20.336925Z","end":"2026-09-22T02:42:20.462722Z","steps":["trace[307293444] 'agreement among raft nodes before linearized reading' (duration: 125.506161ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:20.462843Z","caller":"traceutil/trace.go:171","msg":"trace[1082178340] transaction","detail":"{read_only:false; response_revision:25829; number_of_response:1; }","duration":"140.777731ms","start":"2026-09-22T02:42:20.321485Z","end":"2026-09-22T02:42:20.462263Z","steps":["trace[1082178340] 'process raft request' (duration: 140.692569ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:20.462868Z","caller":"traceutil/trace.go:171","msg":"trace[186202327] transaction","detail":"{read_only:false; response_revision:25828; number_of_response:1; }","duration":"234.952442ms","start":"2026-09-22T02:42:20.227844Z","end":"2026-09-22T02:42:20.462797Z","steps":["trace[186202327] 'process raft request' (duration: 234.214295ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:22.132507Z","caller":"traceutil/trace.go:171","msg":"trace[1776161695] transaction","detail":"{read_only:false; response_revision:25835; number_of_response:1; }","duration":"103.51484ms","start":"2026-09-22T02:42:22.028972Z","end":"2026-09-22T02:42:22.132487Z","steps":["trace[1776161695] 'process raft request' (duration: 103.370436ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:22.334405Z","caller":"traceutil/trace.go:171","msg":"trace[1077436835] transaction","detail":"{read_only:false; response_revision:25837; number_of_response:1; }","duration":"207.355207ms","start":"2026-09-22T02:42:22.127025Z","end":"2026-09-22T02:42:22.33438Z","steps":["trace[1077436835] 'process raft request' (duration: 207.270455ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:22.33475Z","caller":"traceutil/trace.go:171","msg":"trace[213790503] transaction","detail":"{read_only:false; response_revision:25838; number_of_response:1; }","duration":"207.640095ms","start":"2026-09-22T02:42:22.127099Z","end":"2026-09-22T02:42:22.334739Z","steps":["trace[213790503] 'process raft request' (duration: 207.246665ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:22.334738Z","caller":"traceutil/trace.go:171","msg":"trace[232690861] transaction","detail":"{read_only:false; response_revision:25836; number_of_response:1; }","duration":"208.438452ms","start":"2026-09-22T02:42:22.126254Z","end":"2026-09-22T02:42:22.334693Z","steps":["trace[232690861] 'process raft request' (duration: 207.892349ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:22.334993Z","caller":"traceutil/trace.go:171","msg":"trace[633995401] transaction","detail":"{read_only:false; response_revision:25839; number_of_response:1; }","duration":"207.289716ms","start":"2026-09-22T02:42:22.127691Z","end":"2026-09-22T02:42:22.33498Z","steps":["trace[633995401] 'process raft request' (duration: 207.00179ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:22.334999Z","caller":"traceutil/trace.go:171","msg":"trace[357675024] linearizableReadLoop","detail":"{readStateIndex:26686; appliedIndex:26680; }","duration":"202.815367ms","start":"2026-09-22T02:42:22.132163Z","end":"2026-09-22T02:42:22.334978Z","steps":["trace[357675024] 'read index received' (duration: 191.765µs)","trace[357675024] 'applied index is now lower than readState.Index' (duration: 202.622562ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:22.335177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.236261ms","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:6"} {"level":"info","ts":"2026-09-22T02:42:22.335212Z","caller":"traceutil/trace.go:171","msg":"trace[676513804] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:25840; }","duration":"128.289573ms","start":"2026-09-22T02:42:22.20691Z","end":"2026-09-22T02:42:22.3352Z","steps":["trace[676513804] 'agreement among raft nodes before linearized reading' (duration: 128.193451ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:22.335166Z","caller":"traceutil/trace.go:171","msg":"trace[1901888022] transaction","detail":"{read_only:false; response_revision:25840; number_of_response:1; }","duration":"206.509258ms","start":"2026-09-22T02:42:22.128574Z","end":"2026-09-22T02:42:22.335083Z","steps":["trace[1901888022] 'process raft request' (duration: 206.308954ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:22.335236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.053102ms","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-09-22T02:42:22.335275Z","caller":"traceutil/trace.go:171","msg":"trace[299590184] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25840; }","duration":"203.135723ms","start":"2026-09-22T02:42:22.132127Z","end":"2026-09-22T02:42:22.335262Z","steps":["trace[299590184] 'agreement among raft nodes before linearized reading' (duration: 202.9786ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:32.612352Z","caller":"traceutil/trace.go:171","msg":"trace[1777789453] transaction","detail":"{read_only:false; response_revision:25915; number_of_response:1; }","duration":"174.471383ms","start":"2026-09-22T02:42:32.437848Z","end":"2026-09-22T02:42:32.612319Z","steps":["trace[1777789453] 'process raft request' (duration: 139.783959ms)","trace[1777789453] 'compare' (duration: 34.490719ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:42:38.449837Z","caller":"traceutil/trace.go:171","msg":"trace[85815487] transaction","detail":"{read_only:false; response_revision:25951; number_of_response:1; }","duration":"108.675725ms","start":"2026-09-22T02:42:38.341134Z","end":"2026-09-22T02:42:38.44981Z","steps":["trace[85815487] 'process raft request' (duration: 38.929348ms)","trace[85815487] 'compare' (duration: 69.537052ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:38.839337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.60927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:42:38.839757Z","caller":"traceutil/trace.go:171","msg":"trace[2057380632] transaction","detail":"{read_only:false; response_revision:25955; number_of_response:1; }","duration":"429.496743ms","start":"2026-09-22T02:42:38.410232Z","end":"2026-09-22T02:42:38.839729Z","steps":["trace[2057380632] 'process raft request' (duration: 429.404721ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:38.839806Z","caller":"traceutil/trace.go:171","msg":"trace[1725738123] transaction","detail":"{read_only:false; response_revision:25953; number_of_response:1; }","duration":"444.629671ms","start":"2026-09-22T02:42:38.395134Z","end":"2026-09-22T02:42:38.839764Z","steps":["trace[1725738123] 'process raft request' (duration: 444.321224ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:38.839871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:38.410213Z","time spent":"429.604316ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:42:38.839915Z","caller":"traceutil/trace.go:171","msg":"trace[73352476] transaction","detail":"{read_only:false; response_revision:25954; number_of_response:1; }","duration":"442.588606ms","start":"2026-09-22T02:42:38.39729Z","end":"2026-09-22T02:42:38.839878Z","steps":["trace[73352476] 'process raft request' (duration: 442.291509ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:38.839986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:38.395101Z","time spent":"444.786384ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:42:38.839994Z","caller":"traceutil/trace.go:171","msg":"trace[2006953158] linearizableReadLoop","detail":"{readStateIndex:26805; appliedIndex:26800; }","duration":"390.177246ms","start":"2026-09-22T02:42:38.449784Z","end":"2026-09-22T02:42:38.839961Z","steps":["trace[2006953158] 'read index received' (duration: 225.85034ms)","trace[2006953158] 'applied index is now lower than readState.Index' (duration: 164.325546ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:42:38.84013Z","caller":"traceutil/trace.go:171","msg":"trace[209838947] transaction","detail":"{read_only:false; response_revision:25956; number_of_response:1; }","duration":"425.618557ms","start":"2026-09-22T02:42:38.414365Z","end":"2026-09-22T02:42:38.839984Z","steps":["trace[209838947] 'process raft request' (duration: 425.32124ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:38.840179Z","caller":"traceutil/trace.go:171","msg":"trace[655367167] transaction","detail":"{read_only:false; response_revision:25952; number_of_response:1; }","duration":"447.262259ms","start":"2026-09-22T02:42:38.392656Z","end":"2026-09-22T02:42:38.839918Z","steps":["trace[655367167] 'process raft request' (duration: 282.951704ms)","trace[655367167] 'compare' (duration: 163.247972ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:38.840217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:38.397276Z","time spent":"442.727609ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:42:38.840286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.101524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-09-22T02:42:38.840308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:38.392632Z","time spent":"447.614569ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:42:38.840337Z","caller":"traceutil/trace.go:171","msg":"trace[1494512059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25956; }","duration":"424.194756ms","start":"2026-09-22T02:42:38.416126Z","end":"2026-09-22T02:42:38.840321Z","steps":["trace[1494512059] 'agreement among raft nodes before linearized reading' (duration: 423.983901ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:38.840369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:38.416113Z","time spent":"424.248457ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-22T02:42:38.840354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.186865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:42:38.840487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.385224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T02:42:38.840534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.942446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-09-22T02:42:38.840546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.759774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-22T02:42:38.840582Z","caller":"traceutil/trace.go:171","msg":"trace[1813775004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25956; }","duration":"389.412179ms","start":"2026-09-22T02:42:38.451144Z","end":"2026-09-22T02:42:38.840556Z","steps":["trace[1813775004] 'agreement among raft nodes before linearized reading' (duration: 389.102393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:38.840589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.711929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-22T02:42:38.840615Z","caller":"traceutil/trace.go:171","msg":"trace[1465293262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25956; }","duration":"206.829135ms","start":"2026-09-22T02:42:38.633764Z","end":"2026-09-22T02:42:38.840594Z","steps":["trace[1465293262] 'agreement among raft nodes before linearized reading' (duration: 206.614751ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:38.840617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.029602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-22T02:42:38.840653Z","caller":"traceutil/trace.go:171","msg":"trace[1614479006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25956; }","duration":"174.7916ms","start":"2026-09-22T02:42:38.665849Z","end":"2026-09-22T02:42:38.840641Z","steps":["trace[1614479006] 'agreement among raft nodes before linearized reading' (duration: 174.619667ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:38.840598Z","caller":"traceutil/trace.go:171","msg":"trace[843915435] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25956; }","duration":"123.513426ms","start":"2026-09-22T02:42:38.717065Z","end":"2026-09-22T02:42:38.840578Z","steps":["trace[843915435] 'agreement among raft nodes before linearized reading' (duration: 123.364053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:38.840493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.177408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-22T02:42:38.840691Z","caller":"traceutil/trace.go:171","msg":"trace[406067627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25956; }","duration":"139.148905ms","start":"2026-09-22T02:42:38.701527Z","end":"2026-09-22T02:42:38.840676Z","steps":["trace[406067627] 'agreement among raft nodes before linearized reading' (duration: 138.959111ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:38.840774Z","caller":"traceutil/trace.go:171","msg":"trace[337037089] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25956; }","duration":"140.466254ms","start":"2026-09-22T02:42:38.700296Z","end":"2026-09-22T02:42:38.840762Z","steps":["trace[337037089] 'agreement among raft nodes before linearized reading' (duration: 140.051155ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:38.840603Z","caller":"traceutil/trace.go:171","msg":"trace[303940978] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25956; }","duration":"176.053738ms","start":"2026-09-22T02:42:38.664528Z","end":"2026-09-22T02:42:38.840582Z","steps":["trace[303940978] 'agreement among raft nodes before linearized reading' (duration: 175.841323ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:38.840752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:38.451074Z","time spent":"389.659775ms","remote":"127.0.0.1:37030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T02:42:38.840791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:38.414354Z","time spent":"425.873893ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:42:39.204687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.885383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T02:42:39.20515Z","caller":"traceutil/trace.go:171","msg":"trace[595854127] transaction","detail":"{read_only:false; response_revision:25958; number_of_response:1; }","duration":"359.418711ms","start":"2026-09-22T02:42:38.84571Z","end":"2026-09-22T02:42:39.205128Z","steps":["trace[595854127] 'process raft request' (duration: 235.996447ms)","trace[595854127] 'compare' (duration: 122.665987ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:42:39.205Z","caller":"traceutil/trace.go:171","msg":"trace[1251401187] transaction","detail":"{read_only:false; response_revision:25959; number_of_response:1; }","duration":"359.196795ms","start":"2026-09-22T02:42:38.84578Z","end":"2026-09-22T02:42:39.204977Z","steps":["trace[1251401187] 'process raft request' (duration: 358.997171ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:39.205273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:38.845697Z","time spent":"359.504592ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:42:39.205292Z","caller":"traceutil/trace.go:171","msg":"trace[534206502] transaction","detail":"{read_only:false; response_revision:25960; number_of_response:1; }","duration":"358.583271ms","start":"2026-09-22T02:42:38.846668Z","end":"2026-09-22T02:42:39.205252Z","steps":["trace[534206502] 'process raft request' (duration: 358.225803ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:39.205387Z","caller":"traceutil/trace.go:171","msg":"trace[1067632342] linearizableReadLoop","detail":"{readStateIndex:26811; appliedIndex:26805; }","duration":"287.789102ms","start":"2026-09-22T02:42:38.917582Z","end":"2026-09-22T02:42:39.205371Z","steps":["trace[1067632342] 'read index received' (duration: 3.839066ms)","trace[1067632342] 'applied index is now lower than readState.Index' (duration: 283.949396ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:42:39.205433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:38.845753Z","time spent":"359.529483ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:42:39.205512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.904874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:42:39.205532Z","caller":"traceutil/trace.go:171","msg":"trace[1339204090] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:25962; }","duration":"287.989406ms","start":"2026-09-22T02:42:38.917535Z","end":"2026-09-22T02:42:39.205525Z","steps":["trace[1339204090] 'agreement among raft nodes before linearized reading' (duration: 287.907064ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:39.205518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:38.846642Z","time spent":"358.751855ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:42:39.205529Z","caller":"traceutil/trace.go:171","msg":"trace[1361544388] transaction","detail":"{read_only:false; response_revision:25961; number_of_response:1; }","duration":"358.797386ms","start":"2026-09-22T02:42:38.846683Z","end":"2026-09-22T02:42:39.205481Z","steps":["trace[1361544388] 'process raft request' (duration: 358.479739ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:42:39.205529Z","caller":"traceutil/trace.go:171","msg":"trace[1330812263] transaction","detail":"{read_only:false; response_revision:25962; number_of_response:1; }","duration":"358.134912ms","start":"2026-09-22T02:42:38.847324Z","end":"2026-09-22T02:42:39.205459Z","steps":["trace[1330812263] 'process raft request' (duration: 358.018379ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:39.205691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:38.846661Z","time spent":"358.942119ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:42:39.205693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:42:38.847309Z","time spent":"358.299785ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:42:39.20748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.522299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-09-22T02:42:39.207583Z","caller":"traceutil/trace.go:171","msg":"trace[266659340] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:25963; }","duration":"210.664012ms","start":"2026-09-22T02:42:38.996898Z","end":"2026-09-22T02:42:39.207562Z","steps":["trace[266659340] 'agreement among raft nodes before linearized reading' (duration: 210.435987ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:42:39.207874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.63783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:128543"} {"level":"info","ts":"2026-09-22T02:42:39.207938Z","caller":"traceutil/trace.go:171","msg":"trace[1617686349] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:25963; }","duration":"193.735563ms","start":"2026-09-22T02:42:39.014185Z","end":"2026-09-22T02:42:39.207921Z","steps":["trace[1617686349] 'agreement among raft nodes before linearized reading' (duration: 193.530468ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:43:01.292855Z","caller":"traceutil/trace.go:171","msg":"trace[1592807294] transaction","detail":"{read_only:false; response_revision:26268; number_of_response:1; }","duration":"379.274883ms","start":"2026-09-22T02:43:00.913004Z","end":"2026-09-22T02:43:01.292279Z","steps":["trace[1592807294] 'process raft request' (duration: 379.11244ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:43:01.293002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:43:00.912981Z","time spent":"379.954368ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:43:01.30977Z","caller":"traceutil/trace.go:171","msg":"trace[2102941229] transaction","detail":"{read_only:false; response_revision:26269; number_of_response:1; }","duration":"385.591514ms","start":"2026-09-22T02:43:00.924155Z","end":"2026-09-22T02:43:01.309747Z","steps":["trace[2102941229] 'process raft request' (duration: 385.39709ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:43:01.309939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:43:00.924139Z","time spent":"385.711256ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:43:01.31002Z","caller":"traceutil/trace.go:171","msg":"trace[393247681] transaction","detail":"{read_only:false; response_revision:26270; number_of_response:1; }","duration":"385.419491ms","start":"2026-09-22T02:43:00.92458Z","end":"2026-09-22T02:43:01.31Z","steps":["trace[393247681] 'process raft request' (duration: 385.112564ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:43:01.310101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:43:00.924557Z","time spent":"385.505023ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:43:01.310376Z","caller":"traceutil/trace.go:171","msg":"trace[1972112937] transaction","detail":"{read_only:false; response_revision:26271; number_of_response:1; }","duration":"385.239417ms","start":"2026-09-22T02:43:00.925121Z","end":"2026-09-22T02:43:01.310361Z","steps":["trace[1972112937] 'process raft request' (duration: 384.800707ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:43:01.31045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:43:00.92511Z","time spent":"385.304718ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:43:01.310635Z","caller":"traceutil/trace.go:171","msg":"trace[1818053973] transaction","detail":"{read_only:false; response_revision:26272; number_of_response:1; }","duration":"385.210536ms","start":"2026-09-22T02:43:00.925409Z","end":"2026-09-22T02:43:01.310619Z","steps":["trace[1818053973] 'process raft request' (duration: 384.896079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:43:01.310714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:43:00.925401Z","time spent":"385.271908ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:43:01.310844Z","caller":"traceutil/trace.go:171","msg":"trace[1726621348] linearizableReadLoop","detail":"{readStateIndex:27128; appliedIndex:27123; }","duration":"291.653938ms","start":"2026-09-22T02:43:01.019178Z","end":"2026-09-22T02:43:01.310832Z","steps":["trace[1726621348] 'read index received' (duration: 273.046552ms)","trace[1726621348] 'applied index is now lower than readState.Index' (duration: 18.606436ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:43:01.311039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.324271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:43:01.311084Z","caller":"traceutil/trace.go:171","msg":"trace[324770537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26273; }","duration":"265.399353ms","start":"2026-09-22T02:43:01.045673Z","end":"2026-09-22T02:43:01.311072Z","steps":["trace[324770537] 'agreement among raft nodes before linearized reading' (duration: 265.332431ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:43:01.311268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.094048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-22T02:43:01.311314Z","caller":"traceutil/trace.go:171","msg":"trace[1553119981] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26273; }","duration":"292.179709ms","start":"2026-09-22T02:43:01.019124Z","end":"2026-09-22T02:43:01.311304Z","steps":["trace[1553119981] 'agreement among raft nodes before linearized reading' (duration: 292.045836ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:45:01.941405Z","caller":"traceutil/trace.go:171","msg":"trace[1629962860] transaction","detail":"{read_only:false; response_revision:27370; number_of_response:1; }","duration":"102.709802ms","start":"2026-09-22T02:45:01.838669Z","end":"2026-09-22T02:45:01.941379Z","steps":["trace[1629962860] 'process raft request' (duration: 102.496198ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:45:01.941719Z","caller":"traceutil/trace.go:171","msg":"trace[486294133] transaction","detail":"{read_only:false; response_revision:27371; number_of_response:1; }","duration":"102.751683ms","start":"2026-09-22T02:45:01.838921Z","end":"2026-09-22T02:45:01.941673Z","steps":["trace[486294133] 'process raft request' (duration: 102.415486ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:45:04.221028Z","caller":"traceutil/trace.go:171","msg":"trace[2019188846] transaction","detail":"{read_only:false; response_revision:27398; number_of_response:1; }","duration":"274.088366ms","start":"2026-09-22T02:45:03.946908Z","end":"2026-09-22T02:45:04.220997Z","steps":["trace[2019188846] 'process raft request' (duration: 270.590658ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:45:04.227489Z","caller":"traceutil/trace.go:171","msg":"trace[1851929658] linearizableReadLoop","detail":"{readStateIndex:28284; appliedIndex:28279; }","duration":"162.973407ms","start":"2026-09-22T02:45:04.064494Z","end":"2026-09-22T02:45:04.227467Z","steps":["trace[1851929658] 'read index received' (duration: 156.416561ms)","trace[1851929658] 'applied index is now lower than readState.Index' (duration: 6.556136ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:45:04.227752Z","caller":"traceutil/trace.go:171","msg":"trace[1678201332] transaction","detail":"{read_only:false; response_revision:27399; number_of_response:1; }","duration":"280.197883ms","start":"2026-09-22T02:45:03.94754Z","end":"2026-09-22T02:45:04.227738Z","steps":["trace[1678201332] 'process raft request' (duration: 279.652841ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:45:04.227912Z","caller":"traceutil/trace.go:171","msg":"trace[1333134412] transaction","detail":"{read_only:false; response_revision:27400; number_of_response:1; }","duration":"280.199142ms","start":"2026-09-22T02:45:03.9477Z","end":"2026-09-22T02:45:04.2279Z","steps":["trace[1333134412] 'process raft request' (duration: 279.578899ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:45:04.228066Z","caller":"traceutil/trace.go:171","msg":"trace[1723273564] transaction","detail":"{read_only:false; response_revision:27401; number_of_response:1; }","duration":"280.254704ms","start":"2026-09-22T02:45:03.9478Z","end":"2026-09-22T02:45:04.228055Z","steps":["trace[1723273564] 'process raft request' (duration: 279.576559ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:45:04.22823Z","caller":"traceutil/trace.go:171","msg":"trace[14503508] transaction","detail":"{read_only:false; response_revision:27402; number_of_response:1; }","duration":"280.330186ms","start":"2026-09-22T02:45:03.947857Z","end":"2026-09-22T02:45:04.228187Z","steps":["trace[14503508] 'process raft request' (duration: 279.566619ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:45:04.228655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.135653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-22T02:45:04.228697Z","caller":"traceutil/trace.go:171","msg":"trace[497565949] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27402; }","duration":"164.249256ms","start":"2026-09-22T02:45:04.064437Z","end":"2026-09-22T02:45:04.228686Z","steps":["trace[497565949] 'agreement among raft nodes before linearized reading' (duration: 164.04736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:45:04.228808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.414408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T02:45:04.228869Z","caller":"traceutil/trace.go:171","msg":"trace[499142403] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:27402; }","duration":"155.573392ms","start":"2026-09-22T02:45:04.07328Z","end":"2026-09-22T02:45:04.228853Z","steps":["trace[499142403] 'agreement among raft nodes before linearized reading' (duration: 155.438079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:45:04.229032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.630376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-22T02:45:04.229075Z","caller":"traceutil/trace.go:171","msg":"trace[308516524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27402; }","duration":"113.693888ms","start":"2026-09-22T02:45:04.11537Z","end":"2026-09-22T02:45:04.229064Z","steps":["trace[308516524] 'agreement among raft nodes before linearized reading' (duration: 113.591726ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:45:04.229181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.296449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-22T02:45:04.229261Z","caller":"traceutil/trace.go:171","msg":"trace[2023549860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27402; }","duration":"122.393401ms","start":"2026-09-22T02:45:04.106853Z","end":"2026-09-22T02:45:04.229247Z","steps":["trace[2023549860] 'agreement among raft nodes before linearized reading' (duration: 122.211457ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:45:04.229439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.228757ms","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-09-22T02:45:04.22947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.051109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-22T02:45:04.229483Z","caller":"traceutil/trace.go:171","msg":"trace[760638138] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27402; }","duration":"113.286719ms","start":"2026-09-22T02:45:04.11618Z","end":"2026-09-22T02:45:04.229467Z","steps":["trace[760638138] 'agreement among raft nodes before linearized reading' (duration: 113.172446ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:45:04.229503Z","caller":"traceutil/trace.go:171","msg":"trace[1403205747] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27402; }","duration":"146.10654ms","start":"2026-09-22T02:45:04.083387Z","end":"2026-09-22T02:45:04.229493Z","steps":["trace[1403205747] 'agreement among raft nodes before linearized reading' (duration: 146.012468ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:46:39.108013Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25416} {"level":"info","ts":"2026-09-22T02:46:39.277199Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25416,"took":"164.738867ms","hash":888886176,"current-db-size-bytes":57847808,"current-db-size":"58 MB","current-db-size-in-use-bytes":36507648,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-09-22T02:46:39.277298Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":888886176,"revision":25416,"compact-revision":21997} {"level":"info","ts":"2026-09-22T02:48:11.088131Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"64a80de7883e6886","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T02:48:11.093996Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-22T02:48:11.094119Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-22T02:51:39.114107Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28392} {"level":"info","ts":"2026-09-22T02:51:39.257976Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28392,"took":"139.970464ms","hash":1860298254,"current-db-size-bytes":57847808,"current-db-size":"58 MB","current-db-size-in-use-bytes":31453184,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-22T02:51:39.258092Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1860298254,"revision":28392,"compact-revision":25416} {"level":"info","ts":"2026-09-22T02:56:39.120387Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30625} {"level":"info","ts":"2026-09-22T02:56:39.238265Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30625,"took":"113.798838ms","hash":1704393157,"current-db-size-bytes":57847808,"current-db-size":"58 MB","current-db-size-in-use-bytes":31326208,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-22T02:56:39.238338Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1704393157,"revision":30625,"compact-revision":28392} {"level":"info","ts":"2026-09-22T03:01:39.127157Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32882} {"level":"info","ts":"2026-09-22T03:01:39.290551Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32882,"took":"158.831743ms","hash":1704893119,"current-db-size-bytes":58888192,"current-db-size":"59 MB","current-db-size-in-use-bytes":31539200,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-22T03:01:39.290631Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1704893119,"revision":32882,"compact-revision":30625} {"level":"info","ts":"2026-09-22T03:06:39.144528Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35199} {"level":"info","ts":"2026-09-22T03:06:39.268595Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35199,"took":"119.545466ms","hash":1580927040,"current-db-size-bytes":58888192,"current-db-size":"59 MB","current-db-size-in-use-bytes":34189312,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-22T03:06:39.268639Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1580927040,"revision":35199,"compact-revision":32882} {"level":"info","ts":"2026-09-22T03:09:29.970206Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"64a80de7883e6886","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T03:09:29.979198Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-09-22T03:09:29.979449Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-09-22T03:11:39.152171Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37501} {"level":"info","ts":"2026-09-22T03:11:39.314083Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37501,"took":"158.369605ms","hash":2708138755,"current-db-size-bytes":58888192,"current-db-size":"59 MB","current-db-size-in-use-bytes":33140736,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-22T03:11:39.314143Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2708138755,"revision":37501,"compact-revision":35199}