{"level":"warn","ts":"2026-05-09T05:14:00.940472Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T05:14:00.940594Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-09T05:14:00.940671Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T05:14:00.940693Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-09T05:14:00.940722Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T05:14:00.941294Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-05-09T05:14:00.941389Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-09T05:14:00.948267Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.092954ms"} {"level":"info","ts":"2026-05-09T05:14:00.955159Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-05-09T05:14:00.955276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T05:14:00.955462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-05-09T05:14:00.955492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T05:14:00.95551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-05-09T05:14:00.955598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-05-09T05:14:00.960805Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T05:14:00.96605Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T05:14:00.967756Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-09T05:14:00.970056Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T05:14:00.970315Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T05:14:00.970377Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T05:14:00.970504Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T05:14:00.97052Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T05:14:00.971222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-05-09T05:14:00.971381Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-09T05:14:00.973425Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T05:14:00.973557Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-09T05:14:00.973607Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-09T05:14:00.973885Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T05:14:00.973961Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T05:14:01.456545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T05:14:01.456604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T05:14:01.456642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-05-09T05:14:01.456661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-05-09T05:14:01.456673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-09T05:14:01.456695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-05-09T05:14:01.456729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-09T05:14:01.457879Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T05:14:01.4579Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T05:14:01.457914Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T05:14:01.458016Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T05:14:01.45837Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T05:14:01.458413Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T05:14:01.459166Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T05:14:01.459293Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T05:14:01.459332Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T05:14:01.46125Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-05-09T05:14:01.462663Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T05:14:03.986146Z","caller":"traceutil/trace.go:171","msg":"trace[493640806] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"162.474106ms","start":"2026-05-09T05:14:03.823615Z","end":"2026-05-09T05:14:03.986089Z","steps":["trace[493640806] 'process raft request' (duration: 142.957377ms)","trace[493640806] 'compare' (duration: 19.369806ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:14:17.729565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.757569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:14:17.729896Z","caller":"traceutil/trace.go:171","msg":"trace[1863201740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:375; }","duration":"102.175579ms","start":"2026-05-09T05:14:17.627698Z","end":"2026-05-09T05:14:17.729873Z","steps":["trace[1863201740] 'range keys from in-memory index tree' (duration: 101.640067ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:36.692957Z","caller":"traceutil/trace.go:171","msg":"trace[930320589] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"188.463926ms","start":"2026-05-09T05:14:36.504468Z","end":"2026-05-09T05:14:36.692932Z","steps":["trace[930320589] 'process raft request' (duration: 187.823632ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:37.065547Z","caller":"traceutil/trace.go:171","msg":"trace[2130335188] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"134.901055ms","start":"2026-05-09T05:14:36.930626Z","end":"2026-05-09T05:14:37.065527Z","steps":["trace[2130335188] 'read index received' (duration: 134.67322ms)","trace[2130335188] 'applied index is now lower than readState.Index' (duration: 226.925µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:14:37.065758Z","caller":"traceutil/trace.go:171","msg":"trace[601015950] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"313.595034ms","start":"2026-05-09T05:14:36.752009Z","end":"2026-05-09T05:14:37.065604Z","steps":["trace[601015950] 'process raft request' (duration: 313.357939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:37.065829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.179851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bhvhb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-09T05:14:37.065968Z","caller":"traceutil/trace.go:171","msg":"trace[239164862] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bhvhb; range_end:; response_count:1; response_revision:575; }","duration":"135.381165ms","start":"2026-05-09T05:14:36.93057Z","end":"2026-05-09T05:14:37.065951Z","steps":["trace[239164862] 'agreement among raft nodes before linearized reading' (duration: 135.081359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:37.066641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:36.751982Z","time spent":"313.912441ms","remote":"127.0.0.1:40146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T05:14:37.61105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.640818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T05:14:37.611088Z","caller":"traceutil/trace.go:171","msg":"trace[698748098] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"247.633833ms","start":"2026-05-09T05:14:37.36342Z","end":"2026-05-09T05:14:37.611054Z","steps":["trace[698748098] 'process raft request' (duration: 245.018598ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:37.611137Z","caller":"traceutil/trace.go:171","msg":"trace[1257019992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"453.813611ms","start":"2026-05-09T05:14:37.157302Z","end":"2026-05-09T05:14:37.611116Z","steps":["trace[1257019992] 'range keys from in-memory index tree' (duration: 453.451093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:37.611198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:37.15728Z","time spent":"453.908123ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-09T05:14:37.629291Z","caller":"traceutil/trace.go:171","msg":"trace[1318363609] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"169.802402ms","start":"2026-05-09T05:14:37.459441Z","end":"2026-05-09T05:14:37.629243Z","steps":["trace[1318363609] 'process raft request' (duration: 167.605305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:39.304993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.883039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:14:39.305134Z","caller":"traceutil/trace.go:171","msg":"trace[860522999] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"192.217923ms","start":"2026-05-09T05:14:39.11289Z","end":"2026-05-09T05:14:39.305108Z","steps":["trace[860522999] 'process raft request' (duration: 81.925086ms)","trace[860522999] 'compare' (duration: 109.755946ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:14:39.50457Z","caller":"traceutil/trace.go:171","msg":"trace[101766981] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"339.324602ms","start":"2026-05-09T05:14:39.16522Z","end":"2026-05-09T05:14:39.504544Z","steps":["trace[101766981] 'process raft request' (duration: 332.996597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:39.504719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:39.165199Z","time spent":"339.449174ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T05:14:39.506931Z","caller":"traceutil/trace.go:171","msg":"trace[241853081] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"193.808278ms","start":"2026-05-09T05:14:39.313098Z","end":"2026-05-09T05:14:39.506906Z","steps":["trace[241853081] 'process raft request' (duration: 193.765817ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:39.506937Z","caller":"traceutil/trace.go:171","msg":"trace[695660563] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"198.62081ms","start":"2026-05-09T05:14:39.308284Z","end":"2026-05-09T05:14:39.506904Z","steps":["trace[695660563] 'process raft request' (duration: 198.392545ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:39.507088Z","caller":"traceutil/trace.go:171","msg":"trace[907798329] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"197.315651ms","start":"2026-05-09T05:14:39.30975Z","end":"2026-05-09T05:14:39.507066Z","steps":["trace[907798329] 'process raft request' (duration: 197.053976ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:52.209381Z","caller":"traceutil/trace.go:171","msg":"trace[1720579831] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"184.705365ms","start":"2026-05-09T05:14:52.024539Z","end":"2026-05-09T05:14:52.209244Z","steps":["trace[1720579831] 'process raft request' (duration: 184.51916ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:16:02.089262Z","caller":"traceutil/trace.go:171","msg":"trace[999384179] transaction","detail":"{read_only:false; response_revision:1771; number_of_response:1; }","duration":"200.839734ms","start":"2026-05-09T05:16:01.888392Z","end":"2026-05-09T05:16:02.089232Z","steps":["trace[999384179] 'process raft request' (duration: 200.668041ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:16:02.246725Z","caller":"traceutil/trace.go:171","msg":"trace[271077062] transaction","detail":"{read_only:false; response_revision:1772; number_of_response:1; }","duration":"152.773932ms","start":"2026-05-09T05:16:02.093926Z","end":"2026-05-09T05:16:02.2467Z","steps":["trace[271077062] 'process raft request' (duration: 111.687857ms)","trace[271077062] 'compare' (duration: 40.923611ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:16:02.246847Z","caller":"traceutil/trace.go:171","msg":"trace[234906571] linearizableReadLoop","detail":"{readStateIndex:1839; appliedIndex:1837; }","duration":"124.057421ms","start":"2026-05-09T05:16:02.122766Z","end":"2026-05-09T05:16:02.246823Z","steps":["trace[234906571] 'read index received' (duration: 82.864574ms)","trace[234906571] 'applied index is now lower than readState.Index' (duration: 41.192267ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:16:02.247043Z","caller":"traceutil/trace.go:171","msg":"trace[1166307466] transaction","detail":"{read_only:false; response_revision:1773; number_of_response:1; }","duration":"152.718601ms","start":"2026-05-09T05:16:02.094295Z","end":"2026-05-09T05:16:02.247014Z","steps":["trace[1166307466] 'process raft request' (duration: 152.375544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:16:02.247174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.390768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-05-09T05:16:02.247214Z","caller":"traceutil/trace.go:171","msg":"trace[91560117] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1773; }","duration":"124.463069ms","start":"2026-05-09T05:16:02.122739Z","end":"2026-05-09T05:16:02.247202Z","steps":["trace[91560117] 'agreement among raft nodes before linearized reading' (duration: 124.169843ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:16:14.254926Z","caller":"traceutil/trace.go:171","msg":"trace[462956111] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"127.32967ms","start":"2026-05-09T05:16:14.12757Z","end":"2026-05-09T05:16:14.2549Z","steps":["trace[462956111] 'process raft request' (duration: 127.160666ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:16:14.301079Z","caller":"traceutil/trace.go:171","msg":"trace[1411388817] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"136.72154ms","start":"2026-05-09T05:16:14.164324Z","end":"2026-05-09T05:16:14.301045Z","steps":["trace[1411388817] 'process raft request' (duration: 124.592661ms)","trace[1411388817] 'compare' (duration: 11.990916ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:16:48.013478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.361795ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:16:48.013668Z","caller":"traceutil/trace.go:171","msg":"trace[32805909] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"382.717197ms","start":"2026-05-09T05:16:47.630889Z","end":"2026-05-09T05:16:48.013607Z","steps":["trace[32805909] 'process raft request' (duration: 126.966183ms)","trace[32805909] 'compare' (duration: 255.234333ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:16:48.013712Z","caller":"traceutil/trace.go:171","msg":"trace[64875680] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"119.298279ms","start":"2026-05-09T05:16:47.894392Z","end":"2026-05-09T05:16:48.01369Z","steps":["trace[64875680] 'process raft request' (duration: 119.219878ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:16:48.013813Z","caller":"traceutil/trace.go:171","msg":"trace[967063070] linearizableReadLoop","detail":"{readStateIndex:2152; appliedIndex:2151; }","duration":"311.863478ms","start":"2026-05-09T05:16:47.701839Z","end":"2026-05-09T05:16:48.013702Z","steps":["trace[967063070] 'read index received' (duration: 55.91651ms)","trace[967063070] 'applied index is now lower than readState.Index' (duration: 255.941878ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:16:48.013901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:16:47.630873Z","time spent":"382.8983ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:16:48.014236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.423809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-09T05:16:48.014476Z","caller":"traceutil/trace.go:171","msg":"trace[1349554692] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2077; }","duration":"312.794707ms","start":"2026-05-09T05:16:47.701647Z","end":"2026-05-09T05:16:48.014441Z","steps":["trace[1349554692] 'agreement among raft nodes before linearized reading' (duration: 312.196035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:16:48.01454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:16:47.701585Z","time spent":"312.93715ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T05:16:48.161834Z","caller":"traceutil/trace.go:171","msg":"trace[927626777] transaction","detail":"{read_only:false; response_revision:2078; number_of_response:1; }","duration":"143.152527ms","start":"2026-05-09T05:16:48.018649Z","end":"2026-05-09T05:16:48.161801Z","steps":["trace[927626777] 'process raft request' (duration: 141.89202ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:22:03.464073Z","caller":"traceutil/trace.go:171","msg":"trace[560808055] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"128.514746ms","start":"2026-05-09T05:22:03.335535Z","end":"2026-05-09T05:22:03.46405Z","steps":["trace[560808055] 'process raft request' (duration: 128.373813ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:22:03.523316Z","caller":"traceutil/trace.go:171","msg":"trace[1718994900] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"186.24981ms","start":"2026-05-09T05:22:03.337048Z","end":"2026-05-09T05:22:03.523297Z","steps":["trace[1718994900] 'process raft request' (duration: 186.156318ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:22:03.599304Z","caller":"traceutil/trace.go:171","msg":"trace[1410833218] linearizableReadLoop","detail":"{readStateIndex:4925; appliedIndex:4923; }","duration":"127.662777ms","start":"2026-05-09T05:22:03.47162Z","end":"2026-05-09T05:22:03.599283Z","steps":["trace[1410833218] 'read index received' (duration: 51.582064ms)","trace[1410833218] 'applied index is now lower than readState.Index' (duration: 76.079623ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:22:03.599533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.893572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:14497"} {"level":"info","ts":"2026-05-09T05:22:03.599593Z","caller":"traceutil/trace.go:171","msg":"trace[1414589692] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4749; }","duration":"127.988833ms","start":"2026-05-09T05:22:03.471589Z","end":"2026-05-09T05:22:03.599578Z","steps":["trace[1414589692] 'agreement among raft nodes before linearized reading' (duration: 127.82911ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:22:03.59962Z","caller":"traceutil/trace.go:171","msg":"trace[1487385029] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"130.819535ms","start":"2026-05-09T05:22:03.468589Z","end":"2026-05-09T05:22:03.599409Z","steps":["trace[1487385029] 'process raft request' (duration: 89.219572ms)","trace[1487385029] 'compare' (duration: 41.286976ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:22:03.606657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.499904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-09T05:22:03.606754Z","caller":"traceutil/trace.go:171","msg":"trace[1683543479] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4751; }","duration":"118.631767ms","start":"2026-05-09T05:22:03.488091Z","end":"2026-05-09T05:22:03.606723Z","steps":["trace[1683543479] 'agreement among raft nodes before linearized reading' (duration: 118.390792ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:22:08.839282Z","caller":"traceutil/trace.go:171","msg":"trace[1567257668] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"124.179735ms","start":"2026-05-09T05:22:08.71507Z","end":"2026-05-09T05:22:08.83925Z","steps":["trace[1567257668] 'process raft request' (duration: 124.013592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:22:26.018829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.004822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:22:26.018963Z","caller":"traceutil/trace.go:171","msg":"trace[301452904] linearizableReadLoop","detail":"{readStateIndex:5500; appliedIndex:5499; }","duration":"242.471787ms","start":"2026-05-09T05:22:25.776468Z","end":"2026-05-09T05:22:26.01894Z","steps":["trace[301452904] 'read index received' (duration: 61.204039ms)","trace[301452904] 'applied index is now lower than readState.Index' (duration: 181.266018ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:22:26.019085Z","caller":"traceutil/trace.go:171","msg":"trace[373695013] transaction","detail":"{read_only:false; response_revision:5307; number_of_response:1; }","duration":"330.69937ms","start":"2026-05-09T05:22:25.688366Z","end":"2026-05-09T05:22:26.019065Z","steps":["trace[373695013] 'process raft request' (duration: 149.366251ms)","trace[373695013] 'compare' (duration: 180.806298ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:22:26.019178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:22:25.688341Z","time spent":"330.788883ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9564,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:22:26.019398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.334175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-09T05:22:26.019424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.938207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-19761926-ada8-435f-8371-8bc3161d785a\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-09T05:22:26.019493Z","caller":"traceutil/trace.go:171","msg":"trace[1838320032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5307; }","duration":"177.479978ms","start":"2026-05-09T05:22:25.841992Z","end":"2026-05-09T05:22:26.019472Z","steps":["trace[1838320032] 'agreement among raft nodes before linearized reading' (duration: 177.233553ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:22:26.019514Z","caller":"traceutil/trace.go:171","msg":"trace[2064508589] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-19761926-ada8-435f-8371-8bc3161d785a; range_end:; response_count:1; response_revision:5307; }","duration":"243.074449ms","start":"2026-05-09T05:22:25.776416Z","end":"2026-05-09T05:22:26.019491Z","steps":["trace[2064508589] 'agreement among raft nodes before linearized reading' (duration: 242.814674ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:22:26.211081Z","caller":"traceutil/trace.go:171","msg":"trace[1422756248] transaction","detail":"{read_only:false; response_revision:5310; number_of_response:1; }","duration":"184.953817ms","start":"2026-05-09T05:22:26.026085Z","end":"2026-05-09T05:22:26.211039Z","steps":["trace[1422756248] 'process raft request' (duration: 184.794533ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:22:26.211141Z","caller":"traceutil/trace.go:171","msg":"trace[2146972267] transaction","detail":"{read_only:false; response_revision:5309; number_of_response:1; }","duration":"186.243964ms","start":"2026-05-09T05:22:26.024876Z","end":"2026-05-09T05:22:26.21112Z","steps":["trace[2146972267] 'process raft request' (duration: 184.565618ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:22:26.211456Z","caller":"traceutil/trace.go:171","msg":"trace[2114995902] transaction","detail":"{read_only:false; response_revision:5311; number_of_response:1; }","duration":"182.569846ms","start":"2026-05-09T05:22:26.028865Z","end":"2026-05-09T05:22:26.211434Z","steps":["trace[2114995902] 'process raft request' (duration: 182.090916ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:22:35.495548Z","caller":"traceutil/trace.go:171","msg":"trace[1133417933] transaction","detail":"{read_only:false; response_revision:5447; number_of_response:1; }","duration":"121.281555ms","start":"2026-05-09T05:22:35.37423Z","end":"2026-05-09T05:22:35.495511Z","steps":["trace[1133417933] 'process raft request' (duration: 121.02317ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:24:01.49412Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2997} {"level":"info","ts":"2026-05-09T05:24:01.568676Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2997,"took":"72.905947ms","hash":2743681644,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":34103296,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-09T05:24:01.568744Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2743681644,"revision":2997,"compact-revision":-1} {"level":"info","ts":"2026-05-09T05:28:26.072516Z","caller":"traceutil/trace.go:171","msg":"trace[1990581872] transaction","detail":"{read_only:false; response_revision:8108; number_of_response:1; }","duration":"179.578074ms","start":"2026-05-09T05:28:25.892876Z","end":"2026-05-09T05:28:26.072454Z","steps":["trace[1990581872] 'process raft request' (duration: 179.154515ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:28:26.243676Z","caller":"traceutil/trace.go:171","msg":"trace[790236763] transaction","detail":"{read_only:false; response_revision:8109; number_of_response:1; }","duration":"166.010628ms","start":"2026-05-09T05:28:26.077636Z","end":"2026-05-09T05:28:26.243647Z","steps":["trace[790236763] 'process raft request' (duration: 165.832384ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:28:26.244232Z","caller":"traceutil/trace.go:171","msg":"trace[315502346] linearizableReadLoop","detail":"{readStateIndex:8391; appliedIndex:8389; }","duration":"121.828018ms","start":"2026-05-09T05:28:26.122383Z","end":"2026-05-09T05:28:26.244211Z","steps":["trace[315502346] 'read index received' (duration: 121.073442ms)","trace[315502346] 'applied index is now lower than readState.Index' (duration: 753.496µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:28:26.244331Z","caller":"traceutil/trace.go:171","msg":"trace[667878550] transaction","detail":"{read_only:false; response_revision:8110; number_of_response:1; }","duration":"166.435577ms","start":"2026-05-09T05:28:26.077849Z","end":"2026-05-09T05:28:26.244285Z","steps":["trace[667878550] 'process raft request' (duration: 166.248373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:28:26.244436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.046062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-09T05:28:26.2445Z","caller":"traceutil/trace.go:171","msg":"trace[1846937510] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8110; }","duration":"122.124513ms","start":"2026-05-09T05:28:26.122344Z","end":"2026-05-09T05:28:26.244469Z","steps":["trace[1846937510] 'agreement among raft nodes before linearized reading' (duration: 121.98787ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:28:38.347681Z","caller":"traceutil/trace.go:171","msg":"trace[1794391147] linearizableReadLoop","detail":"{readStateIndex:8453; appliedIndex:8452; }","duration":"118.000537ms","start":"2026-05-09T05:28:38.229644Z","end":"2026-05-09T05:28:38.347644Z","steps":["trace[1794391147] 'read index received' (duration: 117.780312ms)","trace[1794391147] 'applied index is now lower than readState.Index' (duration: 218.725µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:28:38.347725Z","caller":"traceutil/trace.go:171","msg":"trace[1626512730] transaction","detail":"{read_only:false; response_revision:8170; number_of_response:1; }","duration":"208.268649ms","start":"2026-05-09T05:28:38.139429Z","end":"2026-05-09T05:28:38.347697Z","steps":["trace[1626512730] 'process raft request' (duration: 208.031334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:28:38.347892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.227921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:28:38.347942Z","caller":"traceutil/trace.go:171","msg":"trace[1244502190] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:8170; }","duration":"118.345614ms","start":"2026-05-09T05:28:38.22958Z","end":"2026-05-09T05:28:38.347926Z","steps":["trace[1244502190] 'agreement among raft nodes before linearized reading' (duration: 118.238642ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:28:38.474784Z","caller":"traceutil/trace.go:171","msg":"trace[2125566134] transaction","detail":"{read_only:false; response_revision:8172; number_of_response:1; }","duration":"121.745205ms","start":"2026-05-09T05:28:38.353006Z","end":"2026-05-09T05:28:38.474752Z","steps":["trace[2125566134] 'process raft request' (duration: 121.649173ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:28:38.474992Z","caller":"traceutil/trace.go:171","msg":"trace[1563552182] transaction","detail":"{read_only:false; response_revision:8171; number_of_response:1; }","duration":"121.903148ms","start":"2026-05-09T05:28:38.35289Z","end":"2026-05-09T05:28:38.474793Z","steps":["trace[1563552182] 'process raft request' (duration: 78.224608ms)","trace[1563552182] 'compare' (duration: 43.328413ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:28:38.475125Z","caller":"traceutil/trace.go:171","msg":"trace[1612857181] transaction","detail":"{read_only:false; response_revision:8173; number_of_response:1; }","duration":"122.054411ms","start":"2026-05-09T05:28:38.353036Z","end":"2026-05-09T05:28:38.47509Z","steps":["trace[1612857181] 'process raft request' (duration: 121.672813ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:28:40.586887Z","caller":"traceutil/trace.go:171","msg":"trace[601261705] transaction","detail":"{read_only:false; response_revision:8181; number_of_response:1; }","duration":"103.254766ms","start":"2026-05-09T05:28:40.483601Z","end":"2026-05-09T05:28:40.586856Z","steps":["trace[601261705] 'process raft request' (duration: 103.064702ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:28:40.599154Z","caller":"traceutil/trace.go:171","msg":"trace[720413990] transaction","detail":"{read_only:false; response_revision:8182; number_of_response:1; }","duration":"115.002524ms","start":"2026-05-09T05:28:40.484117Z","end":"2026-05-09T05:28:40.599119Z","steps":["trace[720413990] 'process raft request' (duration: 114.755468ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:28:40.599188Z","caller":"traceutil/trace.go:171","msg":"trace[1596974610] transaction","detail":"{read_only:false; response_revision:8183; number_of_response:1; }","duration":"113.147875ms","start":"2026-05-09T05:28:40.486007Z","end":"2026-05-09T05:28:40.599154Z","steps":["trace[1596974610] 'process raft request' (duration: 112.983392ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:28:40.599249Z","caller":"traceutil/trace.go:171","msg":"trace[362370156] linearizableReadLoop","detail":"{readStateIndex:8468; appliedIndex:8465; }","duration":"111.630833ms","start":"2026-05-09T05:28:40.487594Z","end":"2026-05-09T05:28:40.599225Z","steps":["trace[362370156] 'read index received' (duration: 99.17425ms)","trace[362370156] 'applied index is now lower than readState.Index' (duration: 12.455493ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:28:40.59939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.760326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:28:40.599426Z","caller":"traceutil/trace.go:171","msg":"trace[1189429865] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8185; }","duration":"111.837667ms","start":"2026-05-09T05:28:40.487578Z","end":"2026-05-09T05:28:40.599416Z","steps":["trace[1189429865] 'agreement among raft nodes before linearized reading' (duration: 111.735685ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:29:01.504611Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6161} {"level":"info","ts":"2026-05-09T05:29:01.61454Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6161,"took":"107.883499ms","hash":417657937,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":22482944,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-09T05:29:01.61463Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":417657937,"revision":6161,"compact-revision":2997} {"level":"info","ts":"2026-05-09T05:29:08.876352Z","caller":"traceutil/trace.go:171","msg":"trace[508103409] transaction","detail":"{read_only:false; response_revision:8388; number_of_response:1; }","duration":"176.808186ms","start":"2026-05-09T05:29:08.699502Z","end":"2026-05-09T05:29:08.87631Z","steps":["trace[508103409] 'process raft request' (duration: 176.432207ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:29:08.992151Z","caller":"traceutil/trace.go:171","msg":"trace[1631352795] linearizableReadLoop","detail":"{readStateIndex:8682; appliedIndex:8681; }","duration":"264.596109ms","start":"2026-05-09T05:29:08.727528Z","end":"2026-05-09T05:29:08.992124Z","steps":["trace[1631352795] 'read index received' (duration: 148.809503ms)","trace[1631352795] 'applied index is now lower than readState.Index' (duration: 115.785286ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:29:08.992258Z","caller":"traceutil/trace.go:171","msg":"trace[1766593501] transaction","detail":"{read_only:false; response_revision:8389; number_of_response:1; }","duration":"290.696721ms","start":"2026-05-09T05:29:08.701506Z","end":"2026-05-09T05:29:08.992203Z","steps":["trace[1766593501] 'process raft request' (duration: 273.268353ms)","trace[1766593501] 'compare' (duration: 17.239694ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:29:08.992414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.430106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-09T05:29:08.992503Z","caller":"traceutil/trace.go:171","msg":"trace[549956135] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8389; }","duration":"246.545898ms","start":"2026-05-09T05:29:08.745919Z","end":"2026-05-09T05:29:08.992465Z","steps":["trace[549956135] 'agreement among raft nodes before linearized reading' (duration: 246.363795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:29:08.992622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.08881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-09T05:29:08.992694Z","caller":"traceutil/trace.go:171","msg":"trace[1711590609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8389; }","duration":"265.198073ms","start":"2026-05-09T05:29:08.727478Z","end":"2026-05-09T05:29:08.992676Z","steps":["trace[1711590609] 'agreement among raft nodes before linearized reading' (duration: 264.764804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:29:08.992717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.175029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-09T05:29:08.992832Z","caller":"traceutil/trace.go:171","msg":"trace[1050605721] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8389; }","duration":"236.326092ms","start":"2026-05-09T05:29:08.756479Z","end":"2026-05-09T05:29:08.992805Z","steps":["trace[1050605721] 'agreement among raft nodes before linearized reading' (duration: 235.968235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:29:20.807674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.809285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T05:29:20.807801Z","caller":"traceutil/trace.go:171","msg":"trace[1846172401] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8493; }","duration":"128.867612ms","start":"2026-05-09T05:29:20.678915Z","end":"2026-05-09T05:29:20.807783Z","steps":["trace[1846172401] 'compare' (duration: 113.696232ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:29:20.858165Z","caller":"traceutil/trace.go:171","msg":"trace[1005844896] transaction","detail":"{read_only:false; response_revision:8494; number_of_response:1; }","duration":"175.08235ms","start":"2026-05-09T05:29:20.683052Z","end":"2026-05-09T05:29:20.858134Z","steps":["trace[1005844896] 'process raft request' (duration: 174.929767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:29:21.405704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.36447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-09T05:29:21.405987Z","caller":"traceutil/trace.go:171","msg":"trace[1254021704] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8502; }","duration":"118.718667ms","start":"2026-05-09T05:29:21.287247Z","end":"2026-05-09T05:29:21.405965Z","steps":["trace[1254021704] 'range keys from in-memory index tree' (duration: 118.142915ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:29:21.448808Z","caller":"traceutil/trace.go:171","msg":"trace[1767057538] transaction","detail":"{read_only:false; response_revision:8503; number_of_response:1; }","duration":"106.587651ms","start":"2026-05-09T05:29:21.342125Z","end":"2026-05-09T05:29:21.448712Z","steps":["trace[1767057538] 'process raft request' (duration: 106.290864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:29:21.702873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.403637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:29:21.70297Z","caller":"traceutil/trace.go:171","msg":"trace[2008346278] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8503; }","duration":"176.547869ms","start":"2026-05-09T05:29:21.526402Z","end":"2026-05-09T05:29:21.702949Z","steps":["trace[2008346278] 'range keys from in-memory index tree' (duration: 176.316474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:29:21.703732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.727452ms","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-05-09T05:29:21.703872Z","caller":"traceutil/trace.go:171","msg":"trace[470604045] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8503; }","duration":"122.991409ms","start":"2026-05-09T05:29:21.580853Z","end":"2026-05-09T05:29:21.703844Z","steps":["trace[470604045] 'range keys from in-memory index tree' (duration: 122.351254ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:30:11.612173Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002471.wal"} {"level":"info","ts":"2026-05-09T05:30:33.880488Z","caller":"traceutil/trace.go:171","msg":"trace[1687162147] transaction","detail":"{read_only:false; response_revision:9231; number_of_response:1; }","duration":"141.543829ms","start":"2026-05-09T05:30:33.738911Z","end":"2026-05-09T05:30:33.880455Z","steps":["trace[1687162147] 'process raft request' (duration: 139.612989ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:31:35.488672Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"56c01ea8fdc90390","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T05:31:35.493434Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-09T05:31:35.493592Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-09T05:34:01.510504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8308} {"level":"info","ts":"2026-05-09T05:34:01.586792Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8308,"took":"73.768164ms","hash":2471484968,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":26583040,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-09T05:34:01.586872Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2471484968,"revision":8308,"compact-revision":6161} {"level":"info","ts":"2026-05-09T05:39:01.516381Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10986} {"level":"info","ts":"2026-05-09T05:39:01.600023Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10986,"took":"80.565259ms","hash":1293558293,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":28057600,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-09T05:39:01.600085Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1293558293,"revision":10986,"compact-revision":8308} {"level":"info","ts":"2026-05-09T05:40:26.319415Z","caller":"traceutil/trace.go:171","msg":"trace[70757793] transaction","detail":"{read_only:false; response_revision:13569; number_of_response:1; }","duration":"185.527783ms","start":"2026-05-09T05:40:26.133859Z","end":"2026-05-09T05:40:26.319386Z","steps":["trace[70757793] 'process raft request' (duration: 185.321369ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:40:42.06684Z","caller":"traceutil/trace.go:171","msg":"trace[140082621] transaction","detail":"{read_only:false; response_revision:13664; number_of_response:1; }","duration":"313.102274ms","start":"2026-05-09T05:40:41.753708Z","end":"2026-05-09T05:40:42.06681Z","steps":["trace[140082621] 'process raft request' (duration: 312.91039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:40:42.067018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:40:41.753674Z","time spent":"313.250747ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:41:03.207807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.381738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a6109137-ec11-41d0-abda-662108f02157\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-05-09T05:41:03.207932Z","caller":"traceutil/trace.go:171","msg":"trace[2007117065] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a6109137-ec11-41d0-abda-662108f02157; range_end:; response_count:1; response_revision:13971; }","duration":"176.570102ms","start":"2026-05-09T05:41:03.031337Z","end":"2026-05-09T05:41:03.207907Z","steps":["trace[2007117065] 'range keys from in-memory index tree' (duration: 176.110053ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:41:55.297658Z","caller":"traceutil/trace.go:171","msg":"trace[2074467455] transaction","detail":"{read_only:false; response_revision:14601; number_of_response:1; }","duration":"106.174514ms","start":"2026-05-09T05:41:55.191447Z","end":"2026-05-09T05:41:55.297621Z","steps":["trace[2074467455] 'process raft request' (duration: 106.00062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:42:29.098248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.951548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:42:29.098375Z","caller":"traceutil/trace.go:171","msg":"trace[736967855] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14776; }","duration":"163.128652ms","start":"2026-05-09T05:42:28.935224Z","end":"2026-05-09T05:42:29.098353Z","steps":["trace[736967855] 'range keys from in-memory index tree' (duration: 162.875427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:42:29.098449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.707701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-09T05:42:29.098503Z","caller":"traceutil/trace.go:171","msg":"trace[1351973781] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14776; }","duration":"160.778043ms","start":"2026-05-09T05:42:28.937704Z","end":"2026-05-09T05:42:29.098482Z","steps":["trace[1351973781] 'range keys from in-memory index tree' (duration: 160.554008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:42:29.098501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.533062ms","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-05-09T05:42:29.098577Z","caller":"traceutil/trace.go:171","msg":"trace[1842240719] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14776; }","duration":"190.636504ms","start":"2026-05-09T05:42:28.907922Z","end":"2026-05-09T05:42:29.098559Z","steps":["trace[1842240719] 'range keys from in-memory index tree' (duration: 190.319068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:42:29.098738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.731735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-z88wh\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-05-09T05:42:29.098784Z","caller":"traceutil/trace.go:171","msg":"trace[716081532] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-z88wh; range_end:; response_count:1; response_revision:14776; }","duration":"164.827127ms","start":"2026-05-09T05:42:28.933948Z","end":"2026-05-09T05:42:29.098775Z","steps":["trace[716081532] 'range keys from in-memory index tree' (duration: 164.545851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:42:29.098817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.399251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-09T05:42:29.098859Z","caller":"traceutil/trace.go:171","msg":"trace[73843067] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14776; }","duration":"157.462482ms","start":"2026-05-09T05:42:28.941385Z","end":"2026-05-09T05:42:29.098848Z","steps":["trace[73843067] 'range keys from in-memory index tree' (duration: 157.28945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:42:29.098951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.270305ms","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-05-09T05:42:29.098979Z","caller":"traceutil/trace.go:171","msg":"trace[1413445751] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14776; }","duration":"164.354337ms","start":"2026-05-09T05:42:28.934618Z","end":"2026-05-09T05:42:29.098973Z","steps":["trace[1413445751] 'range keys from in-memory index tree' (duration: 164.186263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:42:29.099073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.34595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-z88wh\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-05-09T05:42:29.099114Z","caller":"traceutil/trace.go:171","msg":"trace[1677785243] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-z88wh; range_end:; response_count:1; response_revision:14776; }","duration":"145.407012ms","start":"2026-05-09T05:42:28.953697Z","end":"2026-05-09T05:42:29.099104Z","steps":["trace[1677785243] 'range keys from in-memory index tree' (duration: 145.234278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:42:29.099157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.102531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:42:29.099184Z","caller":"traceutil/trace.go:171","msg":"trace[562815078] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14776; }","duration":"164.151463ms","start":"2026-05-09T05:42:28.935025Z","end":"2026-05-09T05:42:29.099176Z","steps":["trace[562815078] 'range keys from in-memory index tree' (duration: 164.03374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:42:29.098271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.620463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:42:29.099263Z","caller":"traceutil/trace.go:171","msg":"trace[1665113038] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:14776; }","duration":"142.664785ms","start":"2026-05-09T05:42:28.956584Z","end":"2026-05-09T05:42:29.099248Z","steps":["trace[1665113038] 'count revisions from in-memory index tree' (duration: 141.563031ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:44:01.523093Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13027} {"level":"info","ts":"2026-05-09T05:44:01.600813Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13027,"took":"74.922805ms","hash":2359661407,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":30965760,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-09T05:44:01.600875Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2359661407,"revision":13027,"compact-revision":10986} {"level":"info","ts":"2026-05-09T05:48:10.372115Z","caller":"traceutil/trace.go:171","msg":"trace[1592042589] transaction","detail":"{read_only:false; response_revision:17326; number_of_response:1; }","duration":"261.512242ms","start":"2026-05-09T05:48:10.110557Z","end":"2026-05-09T05:48:10.372069Z","steps":["trace[1592042589] 'process raft request' (duration: 261.242116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:48:10.372318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.202405ms","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-05-09T05:48:10.372509Z","caller":"traceutil/trace.go:171","msg":"trace[1649732843] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:17326; }","duration":"253.387338ms","start":"2026-05-09T05:48:10.119031Z","end":"2026-05-09T05:48:10.372418Z","steps":["trace[1649732843] 'agreement among raft nodes before linearized reading' (duration: 253.119132ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:48:10.371993Z","caller":"traceutil/trace.go:171","msg":"trace[1002018699] linearizableReadLoop","detail":"{readStateIndex:17912; appliedIndex:17911; }","duration":"252.873166ms","start":"2026-05-09T05:48:10.119094Z","end":"2026-05-09T05:48:10.371967Z","steps":["trace[1002018699] 'read index received' (duration: 252.636511ms)","trace[1002018699] 'applied index is now lower than readState.Index' (duration: 235.695µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:48:10.373189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.241393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-r4mc8\" ","response":"range_response_count:1 size:20873"} {"level":"info","ts":"2026-05-09T05:48:10.373261Z","caller":"traceutil/trace.go:171","msg":"trace[958949773] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-r4mc8; range_end:; response_count:1; response_revision:17326; }","duration":"119.370446ms","start":"2026-05-09T05:48:10.253872Z","end":"2026-05-09T05:48:10.373242Z","steps":["trace[958949773] 'agreement among raft nodes before linearized reading' (duration: 118.966787ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:48:10.702854Z","caller":"traceutil/trace.go:171","msg":"trace[319840685] transaction","detail":"{read_only:false; response_revision:17327; number_of_response:1; }","duration":"311.166885ms","start":"2026-05-09T05:48:10.391659Z","end":"2026-05-09T05:48:10.702826Z","steps":["trace[319840685] 'process raft request' (duration: 310.9655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:48:10.703227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:48:10.391636Z","time spent":"311.310668ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20988,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:48:10.735577Z","caller":"traceutil/trace.go:171","msg":"trace[700503338] linearizableReadLoop","detail":"{readStateIndex:17914; appliedIndex:17912; }","duration":"198.83281ms","start":"2026-05-09T05:48:10.536708Z","end":"2026-05-09T05:48:10.735541Z","steps":["trace[700503338] 'read index received' (duration: 166.065484ms)","trace[700503338] 'applied index is now lower than readState.Index' (duration: 32.765466ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:48:10.735876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.143477ms","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:8"} {"level":"info","ts":"2026-05-09T05:48:10.73598Z","caller":"traceutil/trace.go:171","msg":"trace[1705848133] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:17327; }","duration":"199.31436ms","start":"2026-05-09T05:48:10.536642Z","end":"2026-05-09T05:48:10.735957Z","steps":["trace[1705848133] 'agreement among raft nodes before linearized reading' (duration: 199.036274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:48:10.736033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.400621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T05:48:10.736146Z","caller":"traceutil/trace.go:171","msg":"trace[2134844259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17327; }","duration":"109.630286ms","start":"2026-05-09T05:48:10.626493Z","end":"2026-05-09T05:48:10.736123Z","steps":["trace[2134844259] 'agreement among raft nodes before linearized reading' (duration: 109.241128ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:48:19.764508Z","caller":"traceutil/trace.go:171","msg":"trace[643628379] transaction","detail":"{read_only:false; response_revision:17527; number_of_response:1; }","duration":"133.02269ms","start":"2026-05-09T05:48:19.631459Z","end":"2026-05-09T05:48:19.764482Z","steps":["trace[643628379] 'process raft request' (duration: 132.864906ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:49:01.529147Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15382} {"level":"info","ts":"2026-05-09T05:49:01.628847Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15382,"took":"94.385657ms","hash":2150245517,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":33366016,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-09T05:49:01.628933Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2150245517,"revision":15382,"compact-revision":13027} {"level":"info","ts":"2026-05-09T05:52:12.132325Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"56c01ea8fdc90390","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T05:52:12.138342Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-09T05:52:12.138534Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-09T05:53:41.214767Z","caller":"traceutil/trace.go:171","msg":"trace[1463650944] linearizableReadLoop","detail":"{readStateIndex:20600; appliedIndex:20599; }","duration":"115.042017ms","start":"2026-05-09T05:53:41.099692Z","end":"2026-05-09T05:53:41.214734Z","steps":["trace[1463650944] 'read index received' (duration: 114.738801ms)","trace[1463650944] 'applied index is now lower than readState.Index' (duration: 301.876µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:53:41.214875Z","caller":"traceutil/trace.go:171","msg":"trace[1998762092] transaction","detail":"{read_only:false; response_revision:19927; number_of_response:1; }","duration":"131.673741ms","start":"2026-05-09T05:53:41.083149Z","end":"2026-05-09T05:53:41.214823Z","steps":["trace[1998762092] 'process raft request' (duration: 131.355015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:53:41.21495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.240272ms","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-05-09T05:53:41.214997Z","caller":"traceutil/trace.go:171","msg":"trace[725508384] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:19927; }","duration":"115.354855ms","start":"2026-05-09T05:53:41.099629Z","end":"2026-05-09T05:53:41.214984Z","steps":["trace[725508384] 'agreement among raft nodes before linearized reading' (duration: 115.249362ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:53:46.586686Z","caller":"traceutil/trace.go:171","msg":"trace[1421222157] transaction","detail":"{read_only:false; response_revision:19953; number_of_response:1; }","duration":"112.885131ms","start":"2026-05-09T05:53:46.473748Z","end":"2026-05-09T05:53:46.586634Z","steps":["trace[1421222157] 'process raft request' (duration: 112.552975ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:53:46.752835Z","caller":"traceutil/trace.go:171","msg":"trace[1307901709] transaction","detail":"{read_only:false; response_revision:19955; number_of_response:1; }","duration":"159.089105ms","start":"2026-05-09T05:53:46.593699Z","end":"2026-05-09T05:53:46.752788Z","steps":["trace[1307901709] 'process raft request' (duration: 128.174148ms)","trace[1307901709] 'compare' (duration: 30.246042ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:53:46.75298Z","caller":"traceutil/trace.go:171","msg":"trace[28809733] linearizableReadLoop","detail":"{readStateIndex:20629; appliedIndex:20628; }","duration":"155.830806ms","start":"2026-05-09T05:53:46.597122Z","end":"2026-05-09T05:53:46.752953Z","steps":["trace[28809733] 'read index received' (duration: 124.727354ms)","trace[28809733] 'applied index is now lower than readState.Index' (duration: 31.102282ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:53:46.753155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.328727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T05:53:46.753196Z","caller":"traceutil/trace.go:171","msg":"trace[134107972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19956; }","duration":"125.392719ms","start":"2026-05-09T05:53:46.627793Z","end":"2026-05-09T05:53:46.753186Z","steps":["trace[134107972] 'agreement among raft nodes before linearized reading' (duration: 125.307027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:53:46.754562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.417689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-09T05:53:46.754661Z","caller":"traceutil/trace.go:171","msg":"trace[1192797805] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19956; }","duration":"157.568562ms","start":"2026-05-09T05:53:46.597068Z","end":"2026-05-09T05:53:46.754636Z","steps":["trace[1192797805] 'agreement among raft nodes before linearized reading' (duration: 157.291226ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:53:47.079427Z","caller":"traceutil/trace.go:171","msg":"trace[557922773] transaction","detail":"{read_only:false; response_revision:19957; number_of_response:1; }","duration":"320.407168ms","start":"2026-05-09T05:53:46.758999Z","end":"2026-05-09T05:53:47.079406Z","steps":["trace[557922773] 'process raft request' (duration: 320.286835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:53:47.079564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:53:46.758978Z","time spent":"320.51905ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:53:47.333203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.461492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T05:53:47.333399Z","caller":"traceutil/trace.go:171","msg":"trace[599810614] transaction","detail":"{read_only:false; response_revision:19958; number_of_response:1; }","duration":"249.635702ms","start":"2026-05-09T05:53:47.083742Z","end":"2026-05-09T05:53:47.333377Z","steps":["trace[599810614] 'process raft request' (duration: 98.894355ms)","trace[599810614] 'compare' (duration: 149.953161ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:53:47.333625Z","caller":"traceutil/trace.go:171","msg":"trace[1389461895] transaction","detail":"{read_only:false; response_revision:19959; number_of_response:1; }","duration":"248.980647ms","start":"2026-05-09T05:53:47.084603Z","end":"2026-05-09T05:53:47.333584Z","steps":["trace[1389461895] 'process raft request' (duration: 248.719522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:53:47.637485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.485959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T05:53:47.637668Z","caller":"traceutil/trace.go:171","msg":"trace[1696327135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19960; }","duration":"148.736176ms","start":"2026-05-09T05:53:47.488901Z","end":"2026-05-09T05:53:47.637637Z","steps":["trace[1696327135] 'range keys from in-memory index tree' (duration: 148.262284ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:53:48.012954Z","caller":"traceutil/trace.go:171","msg":"trace[363921533] transaction","detail":"{read_only:false; response_revision:19962; number_of_response:1; }","duration":"309.424415ms","start":"2026-05-09T05:53:47.703493Z","end":"2026-05-09T05:53:48.012917Z","steps":["trace[363921533] 'process raft request' (duration: 309.213149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:53:48.013175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:53:47.703455Z","time spent":"309.600889ms","remote":"127.0.0.1:39908","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-05-09T05:54:01.535613Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17966} {"level":"info","ts":"2026-05-09T05:54:01.674508Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17966,"took":"134.937101ms","hash":4075953194,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":29499392,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T05:54:01.674615Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4075953194,"revision":17966,"compact-revision":15382} {"level":"info","ts":"2026-05-09T05:57:34.821494Z","caller":"traceutil/trace.go:171","msg":"trace[1165028551] transaction","detail":"{read_only:false; response_revision:22349; number_of_response:1; }","duration":"241.732286ms","start":"2026-05-09T05:57:34.579734Z","end":"2026-05-09T05:57:34.821466Z","steps":["trace[1165028551] 'process raft request' (duration: 241.548773ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:57:34.867318Z","caller":"traceutil/trace.go:171","msg":"trace[86317028] linearizableReadLoop","detail":"{readStateIndex:23098; appliedIndex:23096; }","duration":"285.318533ms","start":"2026-05-09T05:57:34.581967Z","end":"2026-05-09T05:57:34.867286Z","steps":["trace[86317028] 'read index received' (duration: 239.372577ms)","trace[86317028] 'applied index is now lower than readState.Index' (duration: 45.944736ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:57:34.867453Z","caller":"traceutil/trace.go:171","msg":"trace[1778916808] transaction","detail":"{read_only:false; response_revision:22350; number_of_response:1; }","duration":"286.156501ms","start":"2026-05-09T05:57:34.581256Z","end":"2026-05-09T05:57:34.867413Z","steps":["trace[1778916808] 'process raft request' (duration: 285.868865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:57:34.867596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.598419ms","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-05-09T05:57:34.867669Z","caller":"traceutil/trace.go:171","msg":"trace[134691368] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:22350; }","duration":"285.701571ms","start":"2026-05-09T05:57:34.581945Z","end":"2026-05-09T05:57:34.867647Z","steps":["trace[134691368] 'agreement among raft nodes before linearized reading' (duration: 285.487766ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:57:35.085843Z","caller":"traceutil/trace.go:171","msg":"trace[1045215107] transaction","detail":"{read_only:false; response_revision:22351; number_of_response:1; }","duration":"214.350655ms","start":"2026-05-09T05:57:34.871428Z","end":"2026-05-09T05:57:35.085779Z","steps":["trace[1045215107] 'process raft request' (duration: 115.219009ms)","trace[1045215107] 'compare' (duration: 98.670587ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:57:35.085877Z","caller":"traceutil/trace.go:171","msg":"trace[1418652087] transaction","detail":"{read_only:false; response_revision:22352; number_of_response:1; }","duration":"185.976492ms","start":"2026-05-09T05:57:34.899863Z","end":"2026-05-09T05:57:35.085839Z","steps":["trace[1418652087] 'process raft request' (duration: 185.863929ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:57:35.086086Z","caller":"traceutil/trace.go:171","msg":"trace[1527227031] linearizableReadLoop","detail":"{readStateIndex:23099; appliedIndex:23098; }","duration":"206.987228ms","start":"2026-05-09T05:57:34.87908Z","end":"2026-05-09T05:57:35.086068Z","steps":["trace[1527227031] 'read index received' (duration: 107.577366ms)","trace[1527227031] 'applied index is now lower than readState.Index' (duration: 99.407872ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:57:35.086338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.247624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T05:57:35.086398Z","caller":"traceutil/trace.go:171","msg":"trace[1086823496] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22352; }","duration":"207.358207ms","start":"2026-05-09T05:57:34.879023Z","end":"2026-05-09T05:57:35.086381Z","steps":["trace[1086823496] 'agreement among raft nodes before linearized reading' (duration: 207.095221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:57:35.086887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.913185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-09T05:57:35.086988Z","caller":"traceutil/trace.go:171","msg":"trace[1770994854] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:22352; }","duration":"125.073318ms","start":"2026-05-09T05:57:34.961903Z","end":"2026-05-09T05:57:35.086976Z","steps":["trace[1770994854] 'agreement among raft nodes before linearized reading' (duration: 124.409585ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:01.541223Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20034} {"level":"info","ts":"2026-05-09T05:59:01.64728Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20034,"took":"102.623551ms","hash":3784232731,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":41082880,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-05-09T05:59:01.647356Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3784232731,"revision":20034,"compact-revision":17966} {"level":"info","ts":"2026-05-09T06:00:54.777224Z","caller":"traceutil/trace.go:171","msg":"trace[943956102] linearizableReadLoop","detail":"{readStateIndex:25277; appliedIndex:25276; }","duration":"148.857822ms","start":"2026-05-09T06:00:54.628339Z","end":"2026-05-09T06:00:54.777197Z","steps":["trace[943956102] 'read index received' (duration: 127.725063ms)","trace[943956102] 'applied index is now lower than readState.Index' (duration: 21.131539ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:00:54.777451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.095138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:00:54.777507Z","caller":"traceutil/trace.go:171","msg":"trace[84109571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24477; }","duration":"149.209331ms","start":"2026-05-09T06:00:54.628285Z","end":"2026-05-09T06:00:54.777494Z","steps":["trace[84109571] 'agreement among raft nodes before linearized reading' (duration: 149.037577ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:54.777867Z","caller":"traceutil/trace.go:171","msg":"trace[1575227631] transaction","detail":"{read_only:false; response_revision:24477; number_of_response:1; }","duration":"169.157444ms","start":"2026-05-09T06:00:54.608693Z","end":"2026-05-09T06:00:54.77785Z","steps":["trace[1575227631] 'process raft request' (duration: 147.352911ms)","trace[1575227631] 'compare' (duration: 20.453705ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:00:54.850326Z","caller":"traceutil/trace.go:171","msg":"trace[765310827] transaction","detail":"{read_only:false; response_revision:24478; number_of_response:1; }","duration":"178.271478ms","start":"2026-05-09T06:00:54.672Z","end":"2026-05-09T06:00:54.850271Z","steps":["trace[765310827] 'process raft request' (duration: 177.91846ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:54.853698Z","caller":"traceutil/trace.go:171","msg":"trace[528295628] transaction","detail":"{read_only:false; response_revision:24479; number_of_response:1; }","duration":"180.330262ms","start":"2026-05-09T06:00:54.673337Z","end":"2026-05-09T06:00:54.853668Z","steps":["trace[528295628] 'process raft request' (duration: 180.127318ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:55.31044Z","caller":"traceutil/trace.go:171","msg":"trace[1863756163] transaction","detail":"{read_only:false; response_revision:24480; number_of_response:1; }","duration":"253.131778ms","start":"2026-05-09T06:00:55.057287Z","end":"2026-05-09T06:00:55.310419Z","steps":["trace[1863756163] 'process raft request' (duration: 252.999665ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:55.310841Z","caller":"traceutil/trace.go:171","msg":"trace[1816184779] linearizableReadLoop","detail":"{readStateIndex:25280; appliedIndex:25280; }","duration":"245.940435ms","start":"2026-05-09T06:00:55.064893Z","end":"2026-05-09T06:00:55.310834Z","steps":["trace[1816184779] 'read index received' (duration: 245.937244ms)","trace[1816184779] 'applied index is now lower than readState.Index' (duration: 2.751µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:00:55.310921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.012076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:00:55.31094Z","caller":"traceutil/trace.go:171","msg":"trace[1125643829] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:24480; }","duration":"246.074878ms","start":"2026-05-09T06:00:55.064858Z","end":"2026-05-09T06:00:55.310933Z","steps":["trace[1125643829] 'agreement among raft nodes before linearized reading' (duration: 246.023357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:55.326559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.501898ms","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-05-09T06:00:55.326615Z","caller":"traceutil/trace.go:171","msg":"trace[953957645] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24481; }","duration":"141.606279ms","start":"2026-05-09T06:00:55.184995Z","end":"2026-05-09T06:00:55.326601Z","steps":["trace[953957645] 'agreement among raft nodes before linearized reading' (duration: 141.456046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:55.326752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.150772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-09T06:00:55.326802Z","caller":"traceutil/trace.go:171","msg":"trace[402751760] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:24481; }","duration":"255.221763ms","start":"2026-05-09T06:00:55.071568Z","end":"2026-05-09T06:00:55.32679Z","steps":["trace[402751760] 'agreement among raft nodes before linearized reading' (duration: 255.08395ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:59.438281Z","caller":"traceutil/trace.go:171","msg":"trace[994655932] transaction","detail":"{read_only:false; response_revision:24516; number_of_response:1; }","duration":"110.161111ms","start":"2026-05-09T06:00:59.328091Z","end":"2026-05-09T06:00:59.438252Z","steps":["trace[994655932] 'process raft request' (duration: 109.915236ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:59.509413Z","caller":"traceutil/trace.go:171","msg":"trace[1352175742] linearizableReadLoop","detail":"{readStateIndex:25318; appliedIndex:25316; }","duration":"129.852929ms","start":"2026-05-09T06:00:59.379532Z","end":"2026-05-09T06:00:59.509385Z","steps":["trace[1352175742] 'read index received' (duration: 58.529994ms)","trace[1352175742] 'applied index is now lower than readState.Index' (duration: 71.321855ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:00:59.509442Z","caller":"traceutil/trace.go:171","msg":"trace[2049350419] transaction","detail":"{read_only:false; response_revision:24517; number_of_response:1; }","duration":"163.285459ms","start":"2026-05-09T06:00:59.346125Z","end":"2026-05-09T06:00:59.50941Z","steps":["trace[2049350419] 'process raft request' (duration: 133.887305ms)","trace[2049350419] 'compare' (duration: 29.229231ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:00:59.509705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.155565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-09T06:00:59.509781Z","caller":"traceutil/trace.go:171","msg":"trace[1486671455] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24517; }","duration":"130.274058ms","start":"2026-05-09T06:00:59.379487Z","end":"2026-05-09T06:00:59.509761Z","steps":["trace[1486671455] 'agreement among raft nodes before linearized reading' (duration: 130.018072ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:59.747617Z","caller":"traceutil/trace.go:171","msg":"trace[608489952] linearizableReadLoop","detail":"{readStateIndex:25319; appliedIndex:25318; }","duration":"130.351859ms","start":"2026-05-09T06:00:59.617238Z","end":"2026-05-09T06:00:59.74759Z","steps":["trace[608489952] 'read index received' (duration: 43.637026ms)","trace[608489952] 'applied index is now lower than readState.Index' (duration: 86.713743ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:00:59.747682Z","caller":"traceutil/trace.go:171","msg":"trace[1967147297] transaction","detail":"{read_only:false; response_revision:24518; number_of_response:1; }","duration":"233.620654ms","start":"2026-05-09T06:00:59.514015Z","end":"2026-05-09T06:00:59.747636Z","steps":["trace[1967147297] 'process raft request' (duration: 146.899291ms)","trace[1967147297] 'compare' (duration: 86.513719ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:00:59.747816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.559374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T06:00:59.747865Z","caller":"traceutil/trace.go:171","msg":"trace[1690294357] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:24518; }","duration":"130.661765ms","start":"2026-05-09T06:00:59.617189Z","end":"2026-05-09T06:00:59.747851Z","steps":["trace[1690294357] 'agreement among raft nodes before linearized reading' (duration: 130.545013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:59.747872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.01314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:00:59.747961Z","caller":"traceutil/trace.go:171","msg":"trace[181391279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24518; }","duration":"120.132532ms","start":"2026-05-09T06:00:59.627806Z","end":"2026-05-09T06:00:59.747938Z","steps":["trace[181391279] 'agreement among raft nodes before linearized reading' (duration: 119.994109ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:00.346152Z","caller":"traceutil/trace.go:171","msg":"trace[1605528289] transaction","detail":"{read_only:false; response_revision:24519; number_of_response:1; }","duration":"391.185812ms","start":"2026-05-09T06:00:59.954942Z","end":"2026-05-09T06:01:00.346127Z","steps":["trace[1605528289] 'process raft request' (duration: 391.013978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:00.346311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:59.954914Z","time spent":"391.323185ms","remote":"127.0.0.1:39908","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-05-09T06:01:00.350989Z","caller":"traceutil/trace.go:171","msg":"trace[752685054] transaction","detail":"{read_only:false; response_revision:24520; number_of_response:1; }","duration":"394.185436ms","start":"2026-05-09T06:00:59.956771Z","end":"2026-05-09T06:01:00.350957Z","steps":["trace[752685054] 'process raft request' (duration: 393.908559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:00.351106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:59.956744Z","time spent":"394.315779ms","remote":"127.0.0.1:39908","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-05-09T06:01:00.351269Z","caller":"traceutil/trace.go:171","msg":"trace[1432527205] transaction","detail":"{read_only:false; response_revision:24522; number_of_response:1; }","duration":"104.44264ms","start":"2026-05-09T06:01:00.246808Z","end":"2026-05-09T06:01:00.351251Z","steps":["trace[1432527205] 'process raft request' (duration: 104.065182ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:00.350982Z","caller":"traceutil/trace.go:171","msg":"trace[16289267] linearizableReadLoop","detail":"{readStateIndex:25321; appliedIndex:25319; }","duration":"345.930851ms","start":"2026-05-09T06:01:00.005025Z","end":"2026-05-09T06:01:00.350956Z","steps":["trace[16289267] 'read index received' (duration: 340.904054ms)","trace[16289267] 'applied index is now lower than readState.Index' (duration: 5.024767ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:00.35145Z","caller":"traceutil/trace.go:171","msg":"trace[1387034585] transaction","detail":"{read_only:false; response_revision:24521; number_of_response:1; }","duration":"286.342094ms","start":"2026-05-09T06:01:00.065054Z","end":"2026-05-09T06:01:00.351396Z","steps":["trace[1387034585] 'process raft request' (duration: 285.756742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:00.35154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.493332ms","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":"warn","ts":"2026-05-09T06:01:00.351512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.290942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-09T06:01:00.35168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.590865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-09T06:01:00.351726Z","caller":"traceutil/trace.go:171","msg":"trace[790224662] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:24523; }","duration":"341.525506ms","start":"2026-05-09T06:01:00.010181Z","end":"2026-05-09T06:01:00.351707Z","steps":["trace[790224662] 'agreement among raft nodes before linearized reading' (duration: 341.271521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:00.351767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:00.010169Z","time spent":"341.586417ms","remote":"127.0.0.1:46200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":9,"response size":31,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"info","ts":"2026-05-09T06:01:00.351771Z","caller":"traceutil/trace.go:171","msg":"trace[1847701954] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24523; }","duration":"170.724948ms","start":"2026-05-09T06:01:00.181022Z","end":"2026-05-09T06:01:00.351747Z","steps":["trace[1847701954] 'agreement among raft nodes before linearized reading' (duration: 170.472313ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:00.351793Z","caller":"traceutil/trace.go:171","msg":"trace[1527501189] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24523; }","duration":"346.594945ms","start":"2026-05-09T06:01:00.004981Z","end":"2026-05-09T06:01:00.351576Z","steps":["trace[1527501189] 'agreement among raft nodes before linearized reading' (duration: 346.060834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:00.351892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:00.004959Z","time spent":"346.922042ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-05-09T06:01:00.673521Z","caller":"traceutil/trace.go:171","msg":"trace[561442783] transaction","detail":"{read_only:false; response_revision:24524; number_of_response:1; }","duration":"316.916134ms","start":"2026-05-09T06:01:00.356571Z","end":"2026-05-09T06:01:00.673487Z","steps":["trace[561442783] 'process raft request' (duration: 283.647937ms)","trace[561442783] 'compare' (duration: 33.029462ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:00.673685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:00.356544Z","time spent":"317.048057ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:01:00.686327Z","caller":"traceutil/trace.go:171","msg":"trace[905795121] transaction","detail":"{read_only:false; response_revision:24525; number_of_response:1; }","duration":"325.017355ms","start":"2026-05-09T06:01:00.361282Z","end":"2026-05-09T06:01:00.686299Z","steps":["trace[905795121] 'process raft request' (duration: 324.77587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:00.686497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:00.361264Z","time spent":"325.136319ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:01:00.686877Z","caller":"traceutil/trace.go:171","msg":"trace[196620613] transaction","detail":"{read_only:false; response_revision:24526; number_of_response:1; }","duration":"317.421714ms","start":"2026-05-09T06:01:00.369443Z","end":"2026-05-09T06:01:00.686865Z","steps":["trace[196620613] 'process raft request' (duration: 316.798931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:00.68695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:00.369427Z","time spent":"317.489635ms","remote":"127.0.0.1:39908","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-05-09T06:01:00.824772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.194757ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-09T06:01:00.826786Z","caller":"traceutil/trace.go:171","msg":"trace[223891348] linearizableReadLoop","detail":"{readStateIndex:25326; appliedIndex:25324; }","duration":"457.742965ms","start":"2026-05-09T06:01:00.369013Z","end":"2026-05-09T06:01:00.826756Z","steps":["trace[223891348] 'read index received' (duration: 271.217553ms)","trace[223891348] 'applied index is now lower than readState.Index' (duration: 186.524242ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:00.826916Z","caller":"traceutil/trace.go:171","msg":"trace[1432328148] transaction","detail":"{read_only:false; response_revision:24527; number_of_response:1; }","duration":"220.039094ms","start":"2026-05-09T06:01:00.606862Z","end":"2026-05-09T06:01:00.826901Z","steps":["trace[1432328148] 'process raft request' (duration: 218.053633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:00.963295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.413839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T06:01:00.96342Z","caller":"traceutil/trace.go:171","msg":"trace[1826493671] linearizableReadLoop","detail":"{readStateIndex:25330; appliedIndex:25329; }","duration":"136.541651ms","start":"2026-05-09T06:01:00.826858Z","end":"2026-05-09T06:01:00.9634Z","steps":["trace[1826493671] 'read index received' (duration: 296.727µs)","trace[1826493671] 'applied index is now lower than readState.Index' (duration: 136.242894ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:00.963518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.493862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-09T06:01:00.9636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.744438ms","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-05-09T06:01:00.963581Z","caller":"traceutil/trace.go:171","msg":"trace[34566202] transaction","detail":"{read_only:false; response_revision:24528; number_of_response:1; }","duration":"169.982051ms","start":"2026-05-09T06:01:00.793562Z","end":"2026-05-09T06:01:00.963544Z","steps":["trace[34566202] 'process raft request' (duration: 33.186164ms)","trace[34566202] 'compare' (duration: 136.245446ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:00.963631Z","caller":"traceutil/trace.go:171","msg":"trace[96488718] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24528; }","duration":"451.82106ms","start":"2026-05-09T06:01:00.511799Z","end":"2026-05-09T06:01:00.96362Z","steps":["trace[96488718] 'agreement among raft nodes before linearized reading' (duration: 451.652326ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:00.963644Z","caller":"traceutil/trace.go:171","msg":"trace[1852668423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24527; }","duration":"594.659835ms","start":"2026-05-09T06:01:00.368938Z","end":"2026-05-09T06:01:00.963598Z","steps":["trace[1852668423] 'agreement among raft nodes before linearized reading' (duration: 458.203617ms)","trace[1852668423] 'range keys from in-memory index tree' (duration: 135.806075ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:00.963713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:00.368912Z","time spent":"594.783927ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T06:01:00.963738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.070875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:01:00.963832Z","caller":"traceutil/trace.go:171","msg":"trace[853903588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24528; }","duration":"331.163167ms","start":"2026-05-09T06:01:00.632638Z","end":"2026-05-09T06:01:00.963802Z","steps":["trace[853903588] 'agreement among raft nodes before linearized reading' (duration: 330.998313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:00.963881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:00.632568Z","time spent":"331.304669ms","remote":"127.0.0.1:39632","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-05-09T06:01:00.963678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:00.511775Z","time spent":"451.896042ms","remote":"127.0.0.1:39826","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-05-09T06:01:01.045684Z","caller":"traceutil/trace.go:171","msg":"trace[327272030] transaction","detail":"{read_only:false; response_revision:24529; number_of_response:1; }","duration":"174.58193ms","start":"2026-05-09T06:01:00.871053Z","end":"2026-05-09T06:01:01.045635Z","steps":["trace[327272030] 'process raft request' (duration: 174.317944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:01.311296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.273618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T06:01:01.311438Z","caller":"traceutil/trace.go:171","msg":"trace[1140066897] transaction","detail":"{read_only:false; response_revision:24530; number_of_response:1; }","duration":"342.291013ms","start":"2026-05-09T06:01:00.969109Z","end":"2026-05-09T06:01:01.3114Z","steps":["trace[1140066897] 'process raft request' (duration: 195.796691ms)","trace[1140066897] 'compare' (duration: 145.610924ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:01.31158Z","caller":"traceutil/trace.go:171","msg":"trace[1449056947] transaction","detail":"{read_only:false; response_revision:24531; number_of_response:1; }","duration":"137.132362ms","start":"2026-05-09T06:01:01.174422Z","end":"2026-05-09T06:01:01.311555Z","steps":["trace[1449056947] 'process raft request' (duration: 136.99872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:01.311649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:00.969084Z","time spent":"342.446936ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:01:01.31182Z","caller":"traceutil/trace.go:171","msg":"trace[883475007] linearizableReadLoop","detail":"{readStateIndex:25332; appliedIndex:25331; }","duration":"266.227767ms","start":"2026-05-09T06:01:01.045579Z","end":"2026-05-09T06:01:01.311807Z","steps":["trace[883475007] 'read index received' (duration: 119.259333ms)","trace[883475007] 'applied index is now lower than readState.Index' (duration: 146.967354ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:01.312316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.964969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:01:01.312389Z","caller":"traceutil/trace.go:171","msg":"trace[1869525887] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:24531; }","duration":"169.985002ms","start":"2026-05-09T06:01:01.142386Z","end":"2026-05-09T06:01:01.312371Z","steps":["trace[1869525887] 'agreement among raft nodes before linearized reading' (duration: 169.86598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:01.312401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.273185ms","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-05-09T06:01:01.312481Z","caller":"traceutil/trace.go:171","msg":"trace[2059081666] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24531; }","duration":"343.368746ms","start":"2026-05-09T06:01:00.969077Z","end":"2026-05-09T06:01:01.312446Z","steps":["trace[2059081666] 'agreement among raft nodes before linearized reading' (duration: 343.159162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:01.312527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:00.969053Z","time spent":"343.463019ms","remote":"127.0.0.1:39826","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-05-09T06:01:01.665238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.681382ms","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-05-09T06:01:01.66532Z","caller":"traceutil/trace.go:171","msg":"trace[1303340621] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24531; }","duration":"222.811755ms","start":"2026-05-09T06:01:01.44249Z","end":"2026-05-09T06:01:01.665301Z","steps":["trace[1303340621] 'range keys from in-memory index tree' (duration: 222.528089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:01.665687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.549582ms","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-05-09T06:01:01.665727Z","caller":"traceutil/trace.go:171","msg":"trace[555646319] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:24531; }","duration":"209.649625ms","start":"2026-05-09T06:01:01.456063Z","end":"2026-05-09T06:01:01.665713Z","steps":["trace[555646319] 'count revisions from in-memory index tree' (duration: 209.487371ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:01.666203Z","caller":"traceutil/trace.go:171","msg":"trace[445154972] transaction","detail":"{read_only:false; response_revision:24532; number_of_response:1; }","duration":"151.328335ms","start":"2026-05-09T06:01:01.51486Z","end":"2026-05-09T06:01:01.666188Z","steps":["trace[445154972] 'process raft request' (duration: 143.749304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:01.920059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.073354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T06:01:01.920248Z","caller":"traceutil/trace.go:171","msg":"trace[1192671818] transaction","detail":"{read_only:false; response_revision:24534; number_of_response:1; }","duration":"160.592053ms","start":"2026-05-09T06:01:01.759632Z","end":"2026-05-09T06:01:01.920224Z","steps":["trace[1192671818] 'process raft request' (duration: 38.241993ms)","trace[1192671818] 'compare' (duration: 121.567903ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:02.58392Z","caller":"traceutil/trace.go:171","msg":"trace[2072915152] transaction","detail":"{read_only:false; response_revision:24535; number_of_response:1; }","duration":"228.877693ms","start":"2026-05-09T06:01:02.355007Z","end":"2026-05-09T06:01:02.583884Z","steps":["trace[2072915152] 'process raft request' (duration: 228.644728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:02.912587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.650508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T06:01:02.912834Z","caller":"traceutil/trace.go:171","msg":"trace[1971673760] transaction","detail":"{read_only:false; response_revision:24537; number_of_response:1; }","duration":"550.913176ms","start":"2026-05-09T06:01:02.361906Z","end":"2026-05-09T06:01:02.912819Z","steps":["trace[1971673760] 'process raft request' (duration: 550.777573ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:02.912872Z","caller":"traceutil/trace.go:171","msg":"trace[1224104402] linearizableReadLoop","detail":"{readStateIndex:25338; appliedIndex:25336; }","duration":"551.693132ms","start":"2026-05-09T06:01:02.361173Z","end":"2026-05-09T06:01:02.912866Z","steps":["trace[1224104402] 'read index received' (duration: 222.453057ms)","trace[1224104402] 'applied index is now lower than readState.Index' (duration: 329.239345ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:02.912871Z","caller":"traceutil/trace.go:171","msg":"trace[396063710] transaction","detail":"{read_only:false; response_revision:24538; number_of_response:1; }","duration":"546.85498ms","start":"2026-05-09T06:01:02.365987Z","end":"2026-05-09T06:01:02.912842Z","steps":["trace[396063710] 'process raft request' (duration: 546.780418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:02.912976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.802074ms","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-05-09T06:01:02.912995Z","caller":"traceutil/trace.go:171","msg":"trace[1584366648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24539; }","duration":"551.843796ms","start":"2026-05-09T06:01:02.361146Z","end":"2026-05-09T06:01:02.91299Z","steps":["trace[1584366648] 'agreement among raft nodes before linearized reading' (duration: 551.761564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:02.912978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:02.361893Z","time spent":"550.994998ms","remote":"127.0.0.1:39908","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-05-09T06:01:02.913014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:02.361136Z","time spent":"551.873746ms","remote":"127.0.0.1:39908","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":"warn","ts":"2026-05-09T06:01:02.913028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:02.365977Z","time spent":"546.966762ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:01:02.912842Z","caller":"traceutil/trace.go:171","msg":"trace[831154893] transaction","detail":"{read_only:false; response_revision:24539; number_of_response:1; }","duration":"219.79977ms","start":"2026-05-09T06:01:02.693029Z","end":"2026-05-09T06:01:02.912829Z","steps":["trace[831154893] 'process raft request' (duration: 219.77051ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:02.912818Z","caller":"traceutil/trace.go:171","msg":"trace[873288967] transaction","detail":"{read_only:false; response_revision:24536; number_of_response:1; }","duration":"554.957772ms","start":"2026-05-09T06:01:02.357831Z","end":"2026-05-09T06:01:02.912789Z","steps":["trace[873288967] 'process raft request' (duration: 343.039068ms)","trace[873288967] 'compare' (duration: 211.411353ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:02.913155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:02.357819Z","time spent":"555.31742ms","remote":"127.0.0.1:39908","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-05-09T06:01:03.062188Z","caller":"traceutil/trace.go:171","msg":"trace[1154017866] linearizableReadLoop","detail":"{readStateIndex:25342; appliedIndex:25341; }","duration":"149.271711ms","start":"2026-05-09T06:01:02.912886Z","end":"2026-05-09T06:01:03.062158Z","steps":["trace[1154017866] 'read index received' (duration: 147.426632ms)","trace[1154017866] 'applied index is now lower than readState.Index' (duration: 1.842969ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:03.062316Z","caller":"traceutil/trace.go:171","msg":"trace[43570739] transaction","detail":"{read_only:false; response_revision:24540; number_of_response:1; }","duration":"228.597856ms","start":"2026-05-09T06:01:02.833688Z","end":"2026-05-09T06:01:03.062286Z","steps":["trace[43570739] 'process raft request' (duration: 226.606745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:03.062361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.665817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:01:03.062436Z","caller":"traceutil/trace.go:171","msg":"trace[1239421945] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:24540; }","duration":"468.77796ms","start":"2026-05-09T06:01:02.593634Z","end":"2026-05-09T06:01:03.062412Z","steps":["trace[1239421945] 'agreement among raft nodes before linearized reading' (duration: 468.621707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:03.062502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:02.593618Z","time spent":"468.865172ms","remote":"127.0.0.1:40588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true "} {"level":"warn","ts":"2026-05-09T06:01:03.062659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.688567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:01:03.062706Z","caller":"traceutil/trace.go:171","msg":"trace[1631436536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24540; }","duration":"434.764469ms","start":"2026-05-09T06:01:02.627928Z","end":"2026-05-09T06:01:03.062693Z","steps":["trace[1631436536] 'agreement among raft nodes before linearized reading' (duration: 434.691897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:03.062741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:02.627906Z","time spent":"434.82822ms","remote":"127.0.0.1:39632","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-05-09T06:01:03.062514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.060776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-09T06:01:03.062866Z","caller":"traceutil/trace.go:171","msg":"trace[732959622] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24540; }","duration":"357.448265ms","start":"2026-05-09T06:01:02.705398Z","end":"2026-05-09T06:01:03.062846Z","steps":["trace[732959622] 'agreement among raft nodes before linearized reading' (duration: 356.990035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:03.062924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:02.705376Z","time spent":"357.532707ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-09T06:01:03.072274Z","caller":"traceutil/trace.go:171","msg":"trace[144622115] transaction","detail":"{read_only:false; response_revision:24541; number_of_response:1; }","duration":"155.417043ms","start":"2026-05-09T06:01:02.916834Z","end":"2026-05-09T06:01:03.072251Z","steps":["trace[144622115] 'process raft request' (duration: 155.204368ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:03.072274Z","caller":"traceutil/trace.go:171","msg":"trace[553402602] transaction","detail":"{read_only:false; response_revision:24542; number_of_response:1; }","duration":"102.437107ms","start":"2026-05-09T06:01:02.969812Z","end":"2026-05-09T06:01:03.072249Z","steps":["trace[553402602] 'process raft request' (duration: 102.373415ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:03.477621Z","caller":"traceutil/trace.go:171","msg":"trace[475507009] transaction","detail":"{read_only:false; response_revision:24545; number_of_response:1; }","duration":"109.606649ms","start":"2026-05-09T06:01:03.367988Z","end":"2026-05-09T06:01:03.477595Z","steps":["trace[475507009] 'process raft request' (duration: 109.433085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:03.799495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.688617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:01:03.799573Z","caller":"traceutil/trace.go:171","msg":"trace[1552266032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24545; }","duration":"170.81055ms","start":"2026-05-09T06:01:03.628743Z","end":"2026-05-09T06:01:03.799554Z","steps":["trace[1552266032] 'range keys from in-memory index tree' (duration: 170.607886ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:03.972439Z","caller":"traceutil/trace.go:171","msg":"trace[18521893] linearizableReadLoop","detail":"{readStateIndex:25348; appliedIndex:25347; }","duration":"279.873377ms","start":"2026-05-09T06:01:03.69254Z","end":"2026-05-09T06:01:03.972414Z","steps":["trace[18521893] 'read index received' (duration: 279.724644ms)","trace[18521893] 'applied index is now lower than readState.Index' (duration: 147.753µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:03.972791Z","caller":"traceutil/trace.go:171","msg":"trace[858834665] transaction","detail":"{read_only:false; response_revision:24546; number_of_response:1; }","duration":"296.174593ms","start":"2026-05-09T06:01:03.676602Z","end":"2026-05-09T06:01:03.972777Z","steps":["trace[858834665] 'process raft request' (duration: 295.57583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:03.973075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.518041ms","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-05-09T06:01:03.973108Z","caller":"traceutil/trace.go:171","msg":"trace[415535296] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24546; }","duration":"280.603653ms","start":"2026-05-09T06:01:03.692495Z","end":"2026-05-09T06:01:03.973099Z","steps":["trace[415535296] 'agreement among raft nodes before linearized reading' (duration: 280.471611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:04.268728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.559451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T06:01:04.268872Z","caller":"traceutil/trace.go:171","msg":"trace[956038512] linearizableReadLoop","detail":"{readStateIndex:25350; appliedIndex:25348; }","duration":"289.641074ms","start":"2026-05-09T06:01:03.979219Z","end":"2026-05-09T06:01:04.26886Z","steps":["trace[956038512] 'read index received' (duration: 40.893759ms)","trace[956038512] 'applied index is now lower than readState.Index' (duration: 248.746645ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:04.269035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.767736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-a6109137-ec11-41d0-abda-662108f02157\" ","response":"range_response_count:1 size:1316"} {"level":"info","ts":"2026-05-09T06:01:04.269055Z","caller":"traceutil/trace.go:171","msg":"trace[621055137] range","detail":"{range_begin:/registry/persistentvolumes/pvc-a6109137-ec11-41d0-abda-662108f02157; range_end:; response_count:1; response_revision:24548; }","duration":"289.949241ms","start":"2026-05-09T06:01:03.979097Z","end":"2026-05-09T06:01:04.269047Z","steps":["trace[621055137] 'agreement among raft nodes before linearized reading' (duration: 289.793977ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:04.269076Z","caller":"traceutil/trace.go:171","msg":"trace[995720240] transaction","detail":"{read_only:false; response_revision:24548; number_of_response:1; }","duration":"291.373891ms","start":"2026-05-09T06:01:03.977697Z","end":"2026-05-09T06:01:04.269071Z","steps":["trace[995720240] 'process raft request' (duration: 291.128706ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:04.269027Z","caller":"traceutil/trace.go:171","msg":"trace[1557775664] transaction","detail":"{read_only:false; response_revision:24547; number_of_response:1; }","duration":"291.646707ms","start":"2026-05-09T06:01:03.977353Z","end":"2026-05-09T06:01:04.269Z","steps":["trace[1557775664] 'process raft request' (duration: 42.742478ms)","trace[1557775664] 'compare' (duration: 248.340867ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:04.838646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.490295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:01:04.83873Z","caller":"traceutil/trace.go:171","msg":"trace[1575780199] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:24549; }","duration":"124.658319ms","start":"2026-05-09T06:01:04.714051Z","end":"2026-05-09T06:01:04.83871Z","steps":["trace[1575780199] 'count revisions from in-memory index tree' (duration: 124.294351ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:14.057277Z","caller":"traceutil/trace.go:171","msg":"trace[794867856] linearizableReadLoop","detail":"{readStateIndex:25447; appliedIndex:25446; }","duration":"284.852282ms","start":"2026-05-09T06:01:13.772387Z","end":"2026-05-09T06:01:14.05724Z","steps":["trace[794867856] 'read index received' (duration: 265.890789ms)","trace[794867856] 'applied index is now lower than readState.Index' (duration: 18.957453ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:14.057529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.030446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4727"} {"level":"info","ts":"2026-05-09T06:01:14.057568Z","caller":"traceutil/trace.go:171","msg":"trace[893311776] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:24644; }","duration":"285.121718ms","start":"2026-05-09T06:01:13.772436Z","end":"2026-05-09T06:01:14.057557Z","steps":["trace[893311776] 'agreement among raft nodes before linearized reading' (duration: 284.985495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:14.057679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.290751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:01:14.057697Z","caller":"traceutil/trace.go:171","msg":"trace[373594784] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:0; response_revision:24644; }","duration":"285.331131ms","start":"2026-05-09T06:01:13.772361Z","end":"2026-05-09T06:01:14.057692Z","steps":["trace[373594784] 'agreement among raft nodes before linearized reading' (duration: 285.299991ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:14.057294Z","caller":"traceutil/trace.go:171","msg":"trace[1341798003] transaction","detail":"{read_only:false; response_revision:24643; number_of_response:1; }","duration":"289.057271ms","start":"2026-05-09T06:01:13.768196Z","end":"2026-05-09T06:01:14.057253Z","steps":["trace[1341798003] 'process raft request' (duration: 270.071518ms)","trace[1341798003] 'compare' (duration: 18.142396ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:14.058916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.631767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2213920"} {"level":"info","ts":"2026-05-09T06:01:14.058987Z","caller":"traceutil/trace.go:171","msg":"trace[1021481935] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:24644; }","duration":"155.722569ms","start":"2026-05-09T06:01:13.903247Z","end":"2026-05-09T06:01:14.058969Z","steps":["trace[1021481935] 'agreement among raft nodes before linearized reading' (duration: 154.660137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:14.501584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.180262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-05-09T06:01:14.501707Z","caller":"traceutil/trace.go:171","msg":"trace[2017147121] transaction","detail":"{read_only:false; response_revision:24645; number_of_response:1; }","duration":"439.182292ms","start":"2026-05-09T06:01:14.0625Z","end":"2026-05-09T06:01:14.501682Z","steps":["trace[2017147121] 'process raft request' (duration: 279.791765ms)","trace[2017147121] 'compare' (duration: 158.902787ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:14.501766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:14.062463Z","time spent":"439.278624ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":729,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T06:01:14.781231Z","caller":"traceutil/trace.go:171","msg":"trace[1647203180] linearizableReadLoop","detail":"{readStateIndex:25451; appliedIndex:25448; }","duration":"693.675669ms","start":"2026-05-09T06:01:14.087512Z","end":"2026-05-09T06:01:14.781188Z","steps":["trace[1647203180] 'read index received' (duration: 254.791893ms)","trace[1647203180] 'applied index is now lower than readState.Index' (duration: 438.881936ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:14.781425Z","caller":"traceutil/trace.go:171","msg":"trace[14298246] transaction","detail":"{read_only:false; response_revision:24646; number_of_response:1; }","duration":"717.75789ms","start":"2026-05-09T06:01:14.063644Z","end":"2026-05-09T06:01:14.781402Z","steps":["trace[14298246] 'process raft request' (duration: 670.723281ms)","trace[14298246] 'compare' (duration: 46.423027ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:14.781563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:14.063609Z","time spent":"717.870243ms","remote":"127.0.0.1:40100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9164,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T06:01:14.781694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.17153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1818"} {"level":"info","ts":"2026-05-09T06:01:14.781781Z","caller":"traceutil/trace.go:171","msg":"trace[2075601091] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:24647; }","duration":"694.303083ms","start":"2026-05-09T06:01:14.087455Z","end":"2026-05-09T06:01:14.781758Z","steps":["trace[2075601091] 'agreement among raft nodes before linearized reading' (duration: 694.045277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:14.781826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:14.087431Z","time spent":"694.386504ms","remote":"127.0.0.1:39736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1841,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-05-09T06:01:14.782082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.137555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:01:14.78214Z","caller":"traceutil/trace.go:171","msg":"trace[1825261423] transaction","detail":"{read_only:false; response_revision:24647; number_of_response:1; }","duration":"717.073216ms","start":"2026-05-09T06:01:14.064974Z","end":"2026-05-09T06:01:14.782047Z","steps":["trace[1825261423] 'process raft request' (duration: 716.025434ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:14.782191Z","caller":"traceutil/trace.go:171","msg":"trace[965201978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24647; }","duration":"154.308178ms","start":"2026-05-09T06:01:14.627864Z","end":"2026-05-09T06:01:14.782172Z","steps":["trace[965201978] 'agreement among raft nodes before linearized reading' (duration: 154.146055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:14.782214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.843357ms","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-05-09T06:01:14.782246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.437296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T06:01:14.782267Z","caller":"traceutil/trace.go:171","msg":"trace[1944641866] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24647; }","duration":"456.929778ms","start":"2026-05-09T06:01:14.32532Z","end":"2026-05-09T06:01:14.78225Z","steps":["trace[1944641866] 'agreement among raft nodes before linearized reading' (duration: 456.714694ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:14.78233Z","caller":"traceutil/trace.go:171","msg":"trace[1646711824] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:24647; }","duration":"350.568628ms","start":"2026-05-09T06:01:14.43174Z","end":"2026-05-09T06:01:14.782308Z","steps":["trace[1646711824] 'agreement among raft nodes before linearized reading' (duration: 350.285252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:14.782322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:14.06496Z","time spent":"717.25643ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T06:01:14.782346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:14.325298Z","time spent":"457.033581ms","remote":"127.0.0.1:39908","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":"warn","ts":"2026-05-09T06:01:14.782393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:14.431717Z","time spent":"350.658881ms","remote":"127.0.0.1:45494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":74,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-09T06:01:14.781753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.671333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-09T06:01:14.782482Z","caller":"traceutil/trace.go:171","msg":"trace[1557918755] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24647; }","duration":"464.47186ms","start":"2026-05-09T06:01:14.317991Z","end":"2026-05-09T06:01:14.782463Z","steps":["trace[1557918755] 'agreement among raft nodes before linearized reading' (duration: 463.54871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:14.782566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:14.317961Z","time spent":"464.587241ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T06:01:15.451307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.188653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-05-09T06:01:15.451425Z","caller":"traceutil/trace.go:171","msg":"trace[692594562] transaction","detail":"{read_only:false; response_revision:24648; number_of_response:1; }","duration":"661.851153ms","start":"2026-05-09T06:01:14.789552Z","end":"2026-05-09T06:01:15.451404Z","steps":["trace[692594562] 'process raft request' (duration: 318.471087ms)","trace[692594562] 'compare' (duration: 342.921487ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:15.451481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:14.789528Z","time spent":"661.929024ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":709,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T06:01:16.015265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.250494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T06:01:16.015607Z","caller":"traceutil/trace.go:171","msg":"trace[1433250454] transaction","detail":"{read_only:false; response_revision:24649; number_of_response:1; }","duration":"1.225738744s","start":"2026-05-09T06:01:14.789826Z","end":"2026-05-09T06:01:16.015565Z","steps":["trace[1433250454] 'process raft request' (duration: 865.065321ms)","trace[1433250454] 'compare' (duration: 359.954848ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:16.015794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:14.789816Z","time spent":"1.225881577s","remote":"127.0.0.1:39908","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-05-09T06:01:16.015851Z","caller":"traceutil/trace.go:171","msg":"trace[294066049] transaction","detail":"{read_only:false; response_revision:24651; number_of_response:1; }","duration":"1.225964848s","start":"2026-05-09T06:01:14.789849Z","end":"2026-05-09T06:01:16.015814Z","steps":["trace[294066049] 'process raft request' (duration: 1.225733733s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.016006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:14.789817Z","time spent":"1.226104851s","remote":"127.0.0.1:39908","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-05-09T06:01:16.015983Z","caller":"traceutil/trace.go:171","msg":"trace[1252209749] linearizableReadLoop","detail":"{readStateIndex:25457; appliedIndex:25451; }","duration":"1.208929596s","start":"2026-05-09T06:01:14.807032Z","end":"2026-05-09T06:01:16.015962Z","steps":["trace[1252209749] 'read index received' (duration: 301.089727ms)","trace[1252209749] 'applied index is now lower than readState.Index' (duration: 907.838769ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:16.016222Z","caller":"traceutil/trace.go:171","msg":"trace[1243061656] transaction","detail":"{read_only:false; response_revision:24654; number_of_response:1; }","duration":"1.061499144s","start":"2026-05-09T06:01:14.95471Z","end":"2026-05-09T06:01:16.016209Z","steps":["trace[1243061656] 'process raft request' (duration: 1.061022053s)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:16.016252Z","caller":"traceutil/trace.go:171","msg":"trace[2130505491] transaction","detail":"{read_only:false; response_revision:24650; number_of_response:1; }","duration":"1.226372248s","start":"2026-05-09T06:01:14.789867Z","end":"2026-05-09T06:01:16.016239Z","steps":["trace[2130505491] 'process raft request' (duration: 1.22557607s)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:16.016323Z","caller":"traceutil/trace.go:171","msg":"trace[1267537333] transaction","detail":"{read_only:false; response_revision:24656; number_of_response:1; }","duration":"1.055396285s","start":"2026-05-09T06:01:14.960918Z","end":"2026-05-09T06:01:16.016314Z","steps":["trace[1267537333] 'process raft request' (duration: 1.054961735s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.016328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:14.954676Z","time spent":"1.061580626s","remote":"127.0.0.1:39908","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-05-09T06:01:16.016383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:14.96088Z","time spent":"1.055469546s","remote":"127.0.0.1:39908","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-05-09T06:01:16.016491Z","caller":"traceutil/trace.go:171","msg":"trace[979938059] transaction","detail":"{read_only:false; response_revision:24655; number_of_response:1; }","duration":"1.058762166s","start":"2026-05-09T06:01:14.957718Z","end":"2026-05-09T06:01:16.016481Z","steps":["trace[979938059] 'process raft request' (duration: 1.058086441s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.016549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:14.957699Z","time spent":"1.058820028s","remote":"127.0.0.1:39908","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-05-09T06:01:16.016666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209641903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3566"} {"level":"warn","ts":"2026-05-09T06:01:16.016689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:14.789862Z","time spent":"1.226415379s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:01:16.016711Z","caller":"traceutil/trace.go:171","msg":"trace[961985664] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:24656; }","duration":"1.209728425s","start":"2026-05-09T06:01:14.806968Z","end":"2026-05-09T06:01:16.016697Z","steps":["trace[961985664] 'agreement among raft nodes before linearized reading' (duration: 1.20952292s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.016783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:14.806957Z","time spent":"1.209816026s","remote":"127.0.0.1:39736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3589,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-05-09T06:01:16.016813Z","caller":"traceutil/trace.go:171","msg":"trace[726044863] transaction","detail":"{read_only:false; response_revision:24657; number_of_response:1; }","duration":"904.283334ms","start":"2026-05-09T06:01:15.112511Z","end":"2026-05-09T06:01:16.016795Z","steps":["trace[726044863] 'process raft request' (duration: 904.139141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.016918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:15.112484Z","time spent":"904.381246ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T06:01:16.016971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136172681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-05-09T06:01:16.017019Z","caller":"traceutil/trace.go:171","msg":"trace[2142037858] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:24659; }","duration":"1.136278564s","start":"2026-05-09T06:01:14.880724Z","end":"2026-05-09T06:01:16.017002Z","steps":["trace[2142037858] 'agreement among raft nodes before linearized reading' (duration: 1.13609858s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.017033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.42363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"warn","ts":"2026-05-09T06:01:16.017096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.661248ms","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-05-09T06:01:16.017119Z","caller":"traceutil/trace.go:171","msg":"trace[132140133] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24659; }","duration":"338.501242ms","start":"2026-05-09T06:01:15.678597Z","end":"2026-05-09T06:01:16.017098Z","steps":["trace[132140133] 'agreement among raft nodes before linearized reading' (duration: 338.259597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.017154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.016468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-09T06:01:16.017192Z","caller":"traceutil/trace.go:171","msg":"trace[477096492] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:24659; }","duration":"944.10559ms","start":"2026-05-09T06:01:15.073079Z","end":"2026-05-09T06:01:16.017184Z","steps":["trace[477096492] 'agreement among raft nodes before linearized reading' (duration: 944.015498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.017197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.77567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T06:01:16.017219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.792376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-09T06:01:16.017094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.741957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-09T06:01:16.01724Z","caller":"traceutil/trace.go:171","msg":"trace[638793181] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:24659; }","duration":"235.856412ms","start":"2026-05-09T06:01:15.781371Z","end":"2026-05-09T06:01:16.017227Z","steps":["trace[638793181] 'agreement among raft nodes before linearized reading' (duration: 235.796901ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:16.017252Z","caller":"traceutil/trace.go:171","msg":"trace[1044141003] transaction","detail":"{read_only:false; response_revision:24658; number_of_response:1; }","duration":"902.273801ms","start":"2026-05-09T06:01:15.114954Z","end":"2026-05-09T06:01:16.017227Z","steps":["trace[1044141003] 'process raft request' (duration: 901.785431ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:16.017136Z","caller":"traceutil/trace.go:171","msg":"trace[488269975] transaction","detail":"{read_only:false; response_revision:24659; number_of_response:1; }","duration":"888.55753ms","start":"2026-05-09T06:01:15.128536Z","end":"2026-05-09T06:01:16.017093Z","steps":["trace[488269975] 'process raft request' (duration: 888.243443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.017391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:15.128494Z","time spent":"888.826806ms","remote":"127.0.0.1:39908","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-05-09T06:01:16.017262Z","caller":"traceutil/trace.go:171","msg":"trace[676750929] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24659; }","duration":"866.951461ms","start":"2026-05-09T06:01:15.150299Z","end":"2026-05-09T06:01:16.017251Z","steps":["trace[676750929] 'agreement among raft nodes before linearized reading' (duration: 866.631405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.017468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:15.150289Z","time spent":"867.164316ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-09T06:01:16.017196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:15.678553Z","time spent":"338.632495ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":584,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-05-09T06:01:16.017074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:14.880682Z","time spent":"1.136384576s","remote":"127.0.0.1:39802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-05-09T06:01:16.017132Z","caller":"traceutil/trace.go:171","msg":"trace[20707539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24659; }","duration":"877.74061ms","start":"2026-05-09T06:01:15.139379Z","end":"2026-05-09T06:01:16.017119Z","steps":["trace[20707539] 'agreement among raft nodes before linearized reading' (duration: 877.638987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.017624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:15.139339Z","time spent":"878.274841ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-09T06:01:16.017276Z","caller":"traceutil/trace.go:171","msg":"trace[1365306125] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24659; }","duration":"472.884348ms","start":"2026-05-09T06:01:15.544366Z","end":"2026-05-09T06:01:16.01725Z","steps":["trace[1365306125] 'agreement among raft nodes before linearized reading' (duration: 472.787886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.017683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:15.544336Z","time spent":"473.333027ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T06:01:16.017227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:15.073049Z","time spent":"944.172902ms","remote":"127.0.0.1:39682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/199.204.45.132\" "} {"level":"info","ts":"2026-05-09T06:01:16.016472Z","caller":"traceutil/trace.go:171","msg":"trace[1271600223] transaction","detail":"{read_only:false; response_revision:24652; number_of_response:1; }","duration":"1.225424798s","start":"2026-05-09T06:01:14.790901Z","end":"2026-05-09T06:01:16.016326Z","steps":["trace[1271600223] 'process raft request' (duration: 1.224726552s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.018986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:14.790892Z","time spent":"1.227305148s","remote":"127.0.0.1:40136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6083,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T06:01:16.019585Z","caller":"traceutil/trace.go:171","msg":"trace[81997735] transaction","detail":"{read_only:false; response_revision:24653; number_of_response:1; }","duration":"1.214772582s","start":"2026-05-09T06:01:14.804744Z","end":"2026-05-09T06:01:16.019517Z","steps":["trace[81997735] 'process raft request' (duration: 1.210936679s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.019759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:15.11494Z","time spent":"902.383954ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T06:01:16.019769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:14.804711Z","time spent":"1.214944346s","remote":"127.0.0.1:40100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9267,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:01:16.437058Z","caller":"traceutil/trace.go:171","msg":"trace[1208038578] transaction","detail":"{read_only:false; response_revision:24661; number_of_response:1; }","duration":"413.493436ms","start":"2026-05-09T06:01:16.023537Z","end":"2026-05-09T06:01:16.43703Z","steps":["trace[1208038578] 'process raft request' (duration: 413.397234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.437227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.02351Z","time spent":"413.628839ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:01:16.437173Z","caller":"traceutil/trace.go:171","msg":"trace[1200089514] transaction","detail":"{read_only:false; response_revision:24660; number_of_response:1; }","duration":"413.647429ms","start":"2026-05-09T06:01:16.023458Z","end":"2026-05-09T06:01:16.437106Z","steps":["trace[1200089514] 'process raft request' (duration: 413.21074ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:16.437479Z","caller":"traceutil/trace.go:171","msg":"trace[830818161] transaction","detail":"{read_only:false; response_revision:24662; number_of_response:1; }","duration":"413.470476ms","start":"2026-05-09T06:01:16.023961Z","end":"2026-05-09T06:01:16.437432Z","steps":["trace[830818161] 'process raft request' (duration: 413.024997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.437518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.023435Z","time spent":"413.915896ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:01:16.43754Z","caller":"traceutil/trace.go:171","msg":"trace[1227351070] linearizableReadLoop","detail":"{readStateIndex:25469; appliedIndex:25464; }","duration":"393.520832ms","start":"2026-05-09T06:01:16.04399Z","end":"2026-05-09T06:01:16.437511Z","steps":["trace[1227351070] 'read index received' (duration: 392.644304ms)","trace[1227351070] 'applied index is now lower than readState.Index' (duration: 874.668µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:16.437596Z","caller":"traceutil/trace.go:171","msg":"trace[480931607] transaction","detail":"{read_only:false; response_revision:24664; number_of_response:1; }","duration":"410.299497ms","start":"2026-05-09T06:01:16.027283Z","end":"2026-05-09T06:01:16.437583Z","steps":["trace[480931607] 'process raft request' (duration: 410.139694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.437664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.027273Z","time spent":"410.355879ms","remote":"127.0.0.1:46200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3198,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T06:01:16.437698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.045148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T06:01:16.437698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.023944Z","time spent":"413.641049ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:01:16.437753Z","caller":"traceutil/trace.go:171","msg":"trace[299472897] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:24664; }","duration":"414.185011ms","start":"2026-05-09T06:01:16.02355Z","end":"2026-05-09T06:01:16.437735Z","steps":["trace[299472897] 'agreement among raft nodes before linearized reading' (duration: 414.066528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.437758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.351011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T06:01:16.437748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.906269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3566"} {"level":"warn","ts":"2026-05-09T06:01:16.437803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.02352Z","time spent":"414.267483ms","remote":"127.0.0.1:46846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"info","ts":"2026-05-09T06:01:16.43782Z","caller":"traceutil/trace.go:171","msg":"trace[858047452] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:24664; }","duration":"411.432362ms","start":"2026-05-09T06:01:16.026368Z","end":"2026-05-09T06:01:16.437801Z","steps":["trace[858047452] 'agreement among raft nodes before linearized reading' (duration: 411.308119ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:16.437838Z","caller":"traceutil/trace.go:171","msg":"trace[245588233] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:24664; }","duration":"410.010062ms","start":"2026-05-09T06:01:16.027801Z","end":"2026-05-09T06:01:16.437811Z","steps":["trace[245588233] 'agreement among raft nodes before linearized reading' (duration: 409.784857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.437855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.026361Z","time spent":"411.485314ms","remote":"127.0.0.1:39778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" "} {"level":"info","ts":"2026-05-09T06:01:16.437861Z","caller":"traceutil/trace.go:171","msg":"trace[1894514798] transaction","detail":"{read_only:false; response_revision:24663; number_of_response:1; }","duration":"413.736231ms","start":"2026-05-09T06:01:16.024114Z","end":"2026-05-09T06:01:16.437851Z","steps":["trace[1894514798] 'process raft request' (duration: 413.150469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.437883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.027796Z","time spent":"410.079213ms","remote":"127.0.0.1:39736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3589,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-05-09T06:01:16.437921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.024106Z","time spent":"413.785343ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:01:16.862Z","caller":"traceutil/trace.go:171","msg":"trace[777170606] transaction","detail":"{read_only:false; response_revision:24665; number_of_response:1; }","duration":"817.311156ms","start":"2026-05-09T06:01:16.044651Z","end":"2026-05-09T06:01:16.861962Z","steps":["trace[777170606] 'process raft request' (duration: 816.985979ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:16.862058Z","caller":"traceutil/trace.go:171","msg":"trace[288926822] transaction","detail":"{read_only:false; response_revision:24666; number_of_response:1; }","duration":"798.642919ms","start":"2026-05-09T06:01:16.063385Z","end":"2026-05-09T06:01:16.862028Z","steps":["trace[288926822] 'process raft request' (duration: 798.452665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.862207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.044626Z","time spent":"817.459259ms","remote":"127.0.0.1:39682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:01:16.862319Z","caller":"traceutil/trace.go:171","msg":"trace[183410507] transaction","detail":"{read_only:false; response_revision:24667; number_of_response:1; }","duration":"527.299595ms","start":"2026-05-09T06:01:16.334989Z","end":"2026-05-09T06:01:16.862289Z","steps":["trace[183410507] 'process raft request' (duration: 526.984777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.86244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.06337Z","time spent":"798.873395ms","remote":"127.0.0.1:39908","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-05-09T06:01:16.86263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.334955Z","time spent":"527.52203ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:01:16.873663Z","caller":"traceutil/trace.go:171","msg":"trace[755410588] linearizableReadLoop","detail":"{readStateIndex:25473; appliedIndex:25469; }","duration":"430.925417ms","start":"2026-05-09T06:01:16.442711Z","end":"2026-05-09T06:01:16.873637Z","steps":["trace[755410588] 'read index received' (duration: 418.909661ms)","trace[755410588] 'applied index is now lower than readState.Index' (duration: 12.014636ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:16.873808Z","caller":"traceutil/trace.go:171","msg":"trace[769124604] transaction","detail":"{read_only:false; response_revision:24669; number_of_response:1; }","duration":"156.052046ms","start":"2026-05-09T06:01:16.717741Z","end":"2026-05-09T06:01:16.873793Z","steps":["trace[769124604] 'process raft request' (duration: 155.827111ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:16.873804Z","caller":"traceutil/trace.go:171","msg":"trace[1429948004] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"432.97677ms","start":"2026-05-09T06:01:16.440785Z","end":"2026-05-09T06:01:16.873762Z","steps":["trace[1429948004] 'process raft request' (duration: 432.633683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.873975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.335104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:01:16.874057Z","caller":"traceutil/trace.go:171","msg":"trace[1461081466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24669; }","duration":"246.482077ms","start":"2026-05-09T06:01:16.627558Z","end":"2026-05-09T06:01:16.87404Z","steps":["trace[1461081466] 'agreement among raft nodes before linearized reading' (duration: 246.319544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.874156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.812987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3216"} {"level":"warn","ts":"2026-05-09T06:01:16.874142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.370916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1343"} {"level":"warn","ts":"2026-05-09T06:01:16.874149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.440752Z","time spent":"433.197184ms","remote":"127.0.0.1:39802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":944,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T06:01:16.874269Z","caller":"traceutil/trace.go:171","msg":"trace[325613132] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:24669; }","duration":"431.546939ms","start":"2026-05-09T06:01:16.442682Z","end":"2026-05-09T06:01:16.874229Z","steps":["trace[325613132] 'agreement among raft nodes before linearized reading' (duration: 431.193012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.874348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.442674Z","time spent":"431.658921ms","remote":"127.0.0.1:39736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1366,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"warn","ts":"2026-05-09T06:01:16.874202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.478622ms","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-05-09T06:01:16.874473Z","caller":"traceutil/trace.go:171","msg":"trace[202502774] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24669; }","duration":"162.811769ms","start":"2026-05-09T06:01:16.711642Z","end":"2026-05-09T06:01:16.874454Z","steps":["trace[202502774] 'agreement among raft nodes before linearized reading' (duration: 162.227357ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:16.874232Z","caller":"traceutil/trace.go:171","msg":"trace[464099736] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24669; }","duration":"425.93074ms","start":"2026-05-09T06:01:16.448282Z","end":"2026-05-09T06:01:16.874212Z","steps":["trace[464099736] 'agreement among raft nodes before linearized reading' (duration: 425.544022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:16.874581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.448263Z","time spent":"426.304888ms","remote":"127.0.0.1:46200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3239,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-09T06:01:17.414064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.705068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-05-09T06:01:17.414248Z","caller":"traceutil/trace.go:171","msg":"trace[1162743944] linearizableReadLoop","detail":"{readStateIndex:25475; appliedIndex:25474; }","duration":"535.271293ms","start":"2026-05-09T06:01:16.878956Z","end":"2026-05-09T06:01:17.414227Z","steps":["trace[1162743944] 'read index received' (duration: 259.406111ms)","trace[1162743944] 'applied index is now lower than readState.Index' (duration: 275.863382ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:17.414303Z","caller":"traceutil/trace.go:171","msg":"trace[1672437088] transaction","detail":"{read_only:false; response_revision:24670; number_of_response:1; }","duration":"536.466509ms","start":"2026-05-09T06:01:16.877795Z","end":"2026-05-09T06:01:17.414261Z","steps":["trace[1672437088] 'process raft request' (duration: 260.471404ms)","trace[1672437088] 'compare' (duration: 274.914731ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:17.414519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.541819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-05-09T06:01:17.414565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.877782Z","time spent":"536.675234ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":794,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T06:01:17.414604Z","caller":"traceutil/trace.go:171","msg":"trace[644130584] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24670; }","duration":"535.661312ms","start":"2026-05-09T06:01:16.878924Z","end":"2026-05-09T06:01:17.414585Z","steps":["trace[644130584] 'agreement among raft nodes before linearized reading' (duration: 535.375786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:17.414652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.8789Z","time spent":"535.743323ms","remote":"127.0.0.1:39826","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-05-09T06:01:17.427216Z","caller":"traceutil/trace.go:171","msg":"trace[960704852] transaction","detail":"{read_only:false; response_revision:24671; number_of_response:1; }","duration":"547.873751ms","start":"2026-05-09T06:01:16.879313Z","end":"2026-05-09T06:01:17.427187Z","steps":["trace[960704852] 'process raft request' (duration: 547.666466ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:17.427291Z","caller":"traceutil/trace.go:171","msg":"trace[1065829837] transaction","detail":"{read_only:false; response_revision:24673; number_of_response:1; }","duration":"541.875623ms","start":"2026-05-09T06:01:16.885402Z","end":"2026-05-09T06:01:17.427277Z","steps":["trace[1065829837] 'process raft request' (duration: 541.75445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:17.427323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.879293Z","time spent":"547.990473ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":802,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T06:01:17.427388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.885388Z","time spent":"541.942985ms","remote":"127.0.0.1:46200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3218,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T06:01:17.427472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.169604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T06:01:17.427533Z","caller":"traceutil/trace.go:171","msg":"trace[1740650671] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:24673; }","duration":"506.285267ms","start":"2026-05-09T06:01:16.921233Z","end":"2026-05-09T06:01:17.427519Z","steps":["trace[1740650671] 'agreement among raft nodes before linearized reading' (duration: 506.138914ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:17.427531Z","caller":"traceutil/trace.go:171","msg":"trace[695372024] transaction","detail":"{read_only:false; response_revision:24672; number_of_response:1; }","duration":"544.57605ms","start":"2026-05-09T06:01:16.882927Z","end":"2026-05-09T06:01:17.427503Z","steps":["trace[695372024] 'process raft request' (duration: 544.184182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:17.42758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.921218Z","time spent":"506.351288ms","remote":"127.0.0.1:40018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-05-09T06:01:17.427677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.882903Z","time spent":"544.712304ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9126,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T06:01:17.42768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.259559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-05-09T06:01:17.427785Z","caller":"traceutil/trace.go:171","msg":"trace[1262543548] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:24673; }","duration":"548.440903ms","start":"2026-05-09T06:01:16.879315Z","end":"2026-05-09T06:01:17.427756Z","steps":["trace[1262543548] 'agreement among raft nodes before linearized reading' (duration: 548.014904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:17.427859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:16.879286Z","time spent":"548.550584ms","remote":"127.0.0.1:39736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1366,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"warn","ts":"2026-05-09T06:01:18.616486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.370168ms","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-05-09T06:01:18.616583Z","caller":"traceutil/trace.go:171","msg":"trace[1709714847] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:24704; }","duration":"120.533951ms","start":"2026-05-09T06:01:18.496022Z","end":"2026-05-09T06:01:18.616556Z","steps":["trace[1709714847] 'count revisions from in-memory index tree' (duration: 120.278966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:18.616601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.500167ms","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-05-09T06:01:18.616685Z","caller":"traceutil/trace.go:171","msg":"trace[1194596909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24704; }","duration":"173.627569ms","start":"2026-05-09T06:01:18.443035Z","end":"2026-05-09T06:01:18.616663Z","steps":["trace[1194596909] 'range keys from in-memory index tree' (duration: 173.323513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:18.616845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.478572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-12a52422-6fee-4d07-8581-465a71358b8f\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-09T06:01:18.616941Z","caller":"traceutil/trace.go:171","msg":"trace[151270991] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-12a52422-6fee-4d07-8581-465a71358b8f; range_end:; response_count:1; response_revision:24704; }","duration":"114.592985ms","start":"2026-05-09T06:01:18.502318Z","end":"2026-05-09T06:01:18.616911Z","steps":["trace[151270991] 'range keys from in-memory index tree' (duration: 114.33725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:18.617156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.456587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T06:01:18.6172Z","caller":"traceutil/trace.go:171","msg":"trace[28887415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24704; }","duration":"174.568859ms","start":"2026-05-09T06:01:18.442618Z","end":"2026-05-09T06:01:18.617187Z","steps":["trace[28887415] 'range keys from in-memory index tree' (duration: 174.272143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:18.617396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.231301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-12a52422-6fee-4d07-8581-465a71358b8f\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-09T06:01:18.6175Z","caller":"traceutil/trace.go:171","msg":"trace[2101453963] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-12a52422-6fee-4d07-8581-465a71358b8f; range_end:; response_count:1; response_revision:24704; }","duration":"102.385325ms","start":"2026-05-09T06:01:18.515083Z","end":"2026-05-09T06:01:18.617468Z","steps":["trace[2101453963] 'range keys from in-memory index tree' (duration: 101.855624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:18.61755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.625102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-09T06:01:18.617953Z","caller":"traceutil/trace.go:171","msg":"trace[704539150] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24704; }","duration":"175.779425ms","start":"2026-05-09T06:01:18.441846Z","end":"2026-05-09T06:01:18.617625Z","steps":["trace[704539150] 'range keys from in-memory index tree' (duration: 175.396378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:21.053251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.50581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T06:01:21.053357Z","caller":"traceutil/trace.go:171","msg":"trace[1280151921] transaction","detail":"{read_only:false; response_revision:24721; number_of_response:1; }","duration":"306.980433ms","start":"2026-05-09T06:01:20.746357Z","end":"2026-05-09T06:01:21.053337Z","steps":["trace[1280151921] 'process raft request' (duration: 163.288229ms)","trace[1280151921] 'compare' (duration: 143.035909ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:21.053404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:20.746332Z","time spent":"307.050175ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:01:21.058308Z","caller":"traceutil/trace.go:171","msg":"trace[1677402064] transaction","detail":"{read_only:false; response_revision:24723; number_of_response:1; }","duration":"182.438676ms","start":"2026-05-09T06:01:20.87582Z","end":"2026-05-09T06:01:21.058259Z","steps":["trace[1677402064] 'process raft request' (duration: 182.340954ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:21.058632Z","caller":"traceutil/trace.go:171","msg":"trace[78602914] transaction","detail":"{read_only:false; response_revision:24722; number_of_response:1; }","duration":"312.141924ms","start":"2026-05-09T06:01:20.746477Z","end":"2026-05-09T06:01:21.058619Z","steps":["trace[78602914] 'process raft request' (duration: 311.512719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:21.058773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:20.746455Z","time spent":"312.238435ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:01:21.058964Z","caller":"traceutil/trace.go:171","msg":"trace[1705074608] transaction","detail":"{read_only:false; response_revision:24724; number_of_response:1; }","duration":"175.299615ms","start":"2026-05-09T06:01:20.883642Z","end":"2026-05-09T06:01:21.058942Z","steps":["trace[1705074608] 'process raft request' (duration: 174.559579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:23.980483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.652823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T06:01:23.980698Z","caller":"traceutil/trace.go:171","msg":"trace[1712097463] transaction","detail":"{read_only:false; response_revision:24757; number_of_response:1; }","duration":"289.078143ms","start":"2026-05-09T06:01:23.691591Z","end":"2026-05-09T06:01:23.980669Z","steps":["trace[1712097463] 'process raft request' (duration: 112.102092ms)","trace[1712097463] 'compare' (duration: 176.508471ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:24.223989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.630366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T06:01:24.22425Z","caller":"traceutil/trace.go:171","msg":"trace[348585321] transaction","detail":"{read_only:false; response_revision:24758; number_of_response:1; }","duration":"532.018544ms","start":"2026-05-09T06:01:23.69221Z","end":"2026-05-09T06:01:24.224229Z","steps":["trace[348585321] 'process raft request' (duration: 424.04267ms)","trace[348585321] 'compare' (duration: 107.116365ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:24.224331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:23.692191Z","time spent":"532.103406ms","remote":"127.0.0.1:39812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:01:24.22459Z","caller":"traceutil/trace.go:171","msg":"trace[1856778741] linearizableReadLoop","detail":"{readStateIndex:25567; appliedIndex:25563; }","duration":"428.173237ms","start":"2026-05-09T06:01:23.796384Z","end":"2026-05-09T06:01:24.224557Z","steps":["trace[1856778741] 'read index received' (duration: 7.393666ms)","trace[1856778741] 'applied index is now lower than readState.Index' (duration: 420.778021ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:24.224671Z","caller":"traceutil/trace.go:171","msg":"trace[612827909] transaction","detail":"{read_only:false; response_revision:24759; number_of_response:1; }","duration":"530.744087ms","start":"2026-05-09T06:01:23.693914Z","end":"2026-05-09T06:01:24.224658Z","steps":["trace[612827909] 'process raft request' (duration: 530.237907ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:24.224714Z","caller":"traceutil/trace.go:171","msg":"trace[1711007598] transaction","detail":"{read_only:false; response_revision:24760; number_of_response:1; }","duration":"530.748937ms","start":"2026-05-09T06:01:23.693955Z","end":"2026-05-09T06:01:24.224704Z","steps":["trace[1711007598] 'process raft request' (duration: 530.463791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:24.22473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:23.693886Z","time spent":"530.813329ms","remote":"127.0.0.1:39924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T06:01:24.224843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:23.693937Z","time spent":"530.806629ms","remote":"127.0.0.1:39924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T06:01:24.224814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.387292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:01:24.224936Z","caller":"traceutil/trace.go:171","msg":"trace[793931737] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:24760; }","duration":"428.572296ms","start":"2026-05-09T06:01:23.796334Z","end":"2026-05-09T06:01:24.224907Z","steps":["trace[793931737] 'agreement among raft nodes before linearized reading' (duration: 428.375571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:24.225014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:23.796315Z","time spent":"428.681128ms","remote":"127.0.0.1:45430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-05-09T06:01:24.226393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.298867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:01:24.226467Z","caller":"traceutil/trace.go:171","msg":"trace[288011235] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:24766; }","duration":"354.42855ms","start":"2026-05-09T06:01:23.872022Z","end":"2026-05-09T06:01:24.22645Z","steps":["trace[288011235] 'agreement among raft nodes before linearized reading' (duration: 354.300237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:24.226513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:23.87199Z","time spent":"354.514072ms","remote":"127.0.0.1:40322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true "} {"level":"info","ts":"2026-05-09T06:01:24.226575Z","caller":"traceutil/trace.go:171","msg":"trace[667908095] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"174.315903ms","start":"2026-05-09T06:01:24.05221Z","end":"2026-05-09T06:01:24.226526Z","steps":["trace[667908095] 'process raft request' (duration: 174.030887ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:24.226711Z","caller":"traceutil/trace.go:171","msg":"trace[1743167764] transaction","detail":"{read_only:false; response_revision:24766; number_of_response:1; }","duration":"172.447543ms","start":"2026-05-09T06:01:24.054251Z","end":"2026-05-09T06:01:24.226699Z","steps":["trace[1743167764] 'process raft request' (duration: 172.025664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:24.227112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.482112ms","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-05-09T06:01:24.227145Z","caller":"traceutil/trace.go:171","msg":"trace[1403077620] transaction","detail":"{read_only:false; response_revision:24761; number_of_response:1; }","duration":"179.32125ms","start":"2026-05-09T06:01:24.047807Z","end":"2026-05-09T06:01:24.227128Z","steps":["trace[1403077620] 'process raft request' (duration: 178.113254ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:24.22725Z","caller":"traceutil/trace.go:171","msg":"trace[164885003] transaction","detail":"{read_only:false; response_revision:24762; number_of_response:1; }","duration":"177.748607ms","start":"2026-05-09T06:01:24.049492Z","end":"2026-05-09T06:01:24.227241Z","steps":["trace[164885003] 'process raft request' (duration: 176.51158ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:24.227153Z","caller":"traceutil/trace.go:171","msg":"trace[1679964815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24766; }","duration":"169.553573ms","start":"2026-05-09T06:01:24.05759Z","end":"2026-05-09T06:01:24.227143Z","steps":["trace[1679964815] 'agreement among raft nodes before linearized reading' (duration: 169.41456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:24.227448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.136895ms","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-05-09T06:01:24.227448Z","caller":"traceutil/trace.go:171","msg":"trace[62255485] transaction","detail":"{read_only:false; response_revision:24763; number_of_response:1; }","duration":"176.96128ms","start":"2026-05-09T06:01:24.050474Z","end":"2026-05-09T06:01:24.227435Z","steps":["trace[62255485] 'process raft request' (duration: 175.595791ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:24.227491Z","caller":"traceutil/trace.go:171","msg":"trace[593020502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24766; }","duration":"170.237267ms","start":"2026-05-09T06:01:24.057241Z","end":"2026-05-09T06:01:24.227479Z","steps":["trace[593020502] 'agreement among raft nodes before linearized reading' (duration: 169.778418ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:24.227547Z","caller":"traceutil/trace.go:171","msg":"trace[1533013246] transaction","detail":"{read_only:false; response_revision:24764; number_of_response:1; }","duration":"177.043992ms","start":"2026-05-09T06:01:24.050494Z","end":"2026-05-09T06:01:24.227538Z","steps":["trace[1533013246] 'process raft request' (duration: 175.711333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:24.227653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.708723ms","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-05-09T06:01:24.227682Z","caller":"traceutil/trace.go:171","msg":"trace[581568195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24766; }","duration":"166.773984ms","start":"2026-05-09T06:01:24.060897Z","end":"2026-05-09T06:01:24.227671Z","steps":["trace[581568195] 'agreement among raft nodes before linearized reading' (duration: 166.08756ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:50.659009Z","caller":"traceutil/trace.go:171","msg":"trace[632502374] transaction","detail":"{read_only:false; response_revision:24977; number_of_response:1; }","duration":"331.847652ms","start":"2026-05-09T06:01:50.327132Z","end":"2026-05-09T06:01:50.658979Z","steps":["trace[632502374] 'process raft request' (duration: 331.671348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:50.659171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:50.32711Z","time spent":"331.978224ms","remote":"127.0.0.1:39908","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-05-09T06:01:50.8555Z","caller":"traceutil/trace.go:171","msg":"trace[2101804836] transaction","detail":"{read_only:false; response_revision:24978; number_of_response:1; }","duration":"526.17206ms","start":"2026-05-09T06:01:50.32928Z","end":"2026-05-09T06:01:50.855452Z","steps":["trace[2101804836] 'process raft request' (duration: 478.697871ms)","trace[2101804836] 'compare' (duration: 47.272145ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:50.855569Z","caller":"traceutil/trace.go:171","msg":"trace[1688806799] transaction","detail":"{read_only:false; response_revision:24980; number_of_response:1; }","duration":"508.479054ms","start":"2026-05-09T06:01:50.347055Z","end":"2026-05-09T06:01:50.855534Z","steps":["trace[1688806799] 'process raft request' (duration: 508.351081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:50.855722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:50.329254Z","time spent":"526.355594ms","remote":"127.0.0.1:39908","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-05-09T06:01:50.855737Z","caller":"traceutil/trace.go:171","msg":"trace[1800721442] transaction","detail":"{read_only:false; response_revision:24981; number_of_response:1; }","duration":"508.240699ms","start":"2026-05-09T06:01:50.347472Z","end":"2026-05-09T06:01:50.855712Z","steps":["trace[1800721442] 'process raft request' (duration: 507.954693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:50.855777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:50.347043Z","time spent":"508.642137ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T06:01:50.855841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:50.347461Z","time spent":"508.32783ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:01:50.855852Z","caller":"traceutil/trace.go:171","msg":"trace[1247798652] transaction","detail":"{read_only:false; response_revision:24979; number_of_response:1; }","duration":"510.593609ms","start":"2026-05-09T06:01:50.345225Z","end":"2026-05-09T06:01:50.855818Z","steps":["trace[1247798652] 'process raft request' (duration: 510.148889ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:50.855934Z","caller":"traceutil/trace.go:171","msg":"trace[171697211] transaction","detail":"{read_only:false; response_revision:24982; number_of_response:1; }","duration":"507.576805ms","start":"2026-05-09T06:01:50.348346Z","end":"2026-05-09T06:01:50.855922Z","steps":["trace[171697211] 'process raft request' (duration: 507.100255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:50.855959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:50.345211Z","time spent":"510.693621ms","remote":"127.0.0.1:39908","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-05-09T06:01:50.855985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:50.348322Z","time spent":"507.636306ms","remote":"127.0.0.1:39908","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-05-09T06:01:51.033831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.32869ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-09T06:01:51.033939Z","caller":"traceutil/trace.go:171","msg":"trace[378779968] linearizableReadLoop","detail":"{readStateIndex:25795; appliedIndex:25789; }","duration":"684.535115ms","start":"2026-05-09T06:01:50.349381Z","end":"2026-05-09T06:01:51.033916Z","steps":["trace[378779968] 'read index received' (duration: 309.497767ms)","trace[378779968] 'applied index is now lower than readState.Index' (duration: 375.035468ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:51.034213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.818821ms","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-05-09T06:01:51.034254Z","caller":"traceutil/trace.go:171","msg":"trace[73798253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24982; }","duration":"684.894053ms","start":"2026-05-09T06:01:50.34935Z","end":"2026-05-09T06:01:51.034244Z","steps":["trace[73798253] 'agreement among raft nodes before linearized reading' (duration: 684.705019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:51.034319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:50.349338Z","time spent":"684.972055ms","remote":"127.0.0.1:39908","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":"warn","ts":"2026-05-09T06:01:51.034661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.79962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-09T06:01:51.0347Z","caller":"traceutil/trace.go:171","msg":"trace[470580507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24982; }","duration":"603.876572ms","start":"2026-05-09T06:01:50.430815Z","end":"2026-05-09T06:01:51.034692Z","steps":["trace[470580507] 'agreement among raft nodes before linearized reading' (duration: 603.778569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:51.034731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:50.430795Z","time spent":"603.926272ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T06:01:51.034845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.824602ms","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-05-09T06:01:51.034876Z","caller":"traceutil/trace.go:171","msg":"trace[1706342668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24982; }","duration":"140.915164ms","start":"2026-05-09T06:01:50.893953Z","end":"2026-05-09T06:01:51.034869Z","steps":["trace[1706342668] 'agreement among raft nodes before linearized reading' (duration: 140.835963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:51.034987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.131142ms","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-05-09T06:01:51.035016Z","caller":"traceutil/trace.go:171","msg":"trace[882013145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24982; }","duration":"603.476942ms","start":"2026-05-09T06:01:50.431532Z","end":"2026-05-09T06:01:51.035009Z","steps":["trace[882013145] 'agreement among raft nodes before linearized reading' (duration: 603.404651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:51.03504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:50.431523Z","time spent":"603.511243ms","remote":"127.0.0.1:39908","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-05-09T06:01:51.394864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.477018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T06:01:51.395117Z","caller":"traceutil/trace.go:171","msg":"trace[2018865949] linearizableReadLoop","detail":"{readStateIndex:25801; appliedIndex:25797; }","duration":"201.058322ms","start":"2026-05-09T06:01:51.194041Z","end":"2026-05-09T06:01:51.395099Z","steps":["trace[2018865949] 'read index received' (duration: 50.215846ms)","trace[2018865949] 'applied index is now lower than readState.Index' (duration: 150.841596ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:51.395213Z","caller":"traceutil/trace.go:171","msg":"trace[1056175497] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"355.762879ms","start":"2026-05-09T06:01:51.039435Z","end":"2026-05-09T06:01:51.395198Z","steps":["trace[1056175497] 'process raft request' (duration: 204.865252ms)","trace[1056175497] 'compare' (duration: 150.333225ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:51.395278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:51.039415Z","time spent":"355.829431ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T06:01:51.395556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.501672ms","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-05-09T06:01:51.395657Z","caller":"traceutil/trace.go:171","msg":"trace[1918596243] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24987; }","duration":"201.651375ms","start":"2026-05-09T06:01:51.193984Z","end":"2026-05-09T06:01:51.395635Z","steps":["trace[1918596243] 'agreement among raft nodes before linearized reading' (duration: 201.4335ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:51.39577Z","caller":"traceutil/trace.go:171","msg":"trace[362841637] transaction","detail":"{read_only:false; response_revision:24986; number_of_response:1; }","duration":"354.530944ms","start":"2026-05-09T06:01:51.041228Z","end":"2026-05-09T06:01:51.395759Z","steps":["trace[362841637] 'process raft request' (duration: 353.788808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:51.395846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:51.04121Z","time spent":"354.601575ms","remote":"127.0.0.1:39908","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-05-09T06:01:51.395876Z","caller":"traceutil/trace.go:171","msg":"trace[1542153591] transaction","detail":"{read_only:false; response_revision:24987; number_of_response:1; }","duration":"232.915729ms","start":"2026-05-09T06:01:51.162948Z","end":"2026-05-09T06:01:51.395864Z","steps":["trace[1542153591] 'process raft request' (duration: 232.114152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:51.395609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.924783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-09T06:01:51.395991Z","caller":"traceutil/trace.go:171","msg":"trace[1607854748] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24987; }","duration":"196.341512ms","start":"2026-05-09T06:01:51.199638Z","end":"2026-05-09T06:01:51.39598Z","steps":["trace[1607854748] 'agreement among raft nodes before linearized reading' (duration: 195.864042ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:51.396015Z","caller":"traceutil/trace.go:171","msg":"trace[1429406447] transaction","detail":"{read_only:false; response_revision:24985; number_of_response:1; }","duration":"356.497225ms","start":"2026-05-09T06:01:51.039508Z","end":"2026-05-09T06:01:51.396005Z","steps":["trace[1429406447] 'process raft request' (duration: 355.457523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:51.396185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:51.039476Z","time spent":"356.644858ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T06:01:51.712485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.552904ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T06:01:51.713082Z","caller":"traceutil/trace.go:171","msg":"trace[267376417] linearizableReadLoop","detail":"{readStateIndex:25804; appliedIndex:25801; }","duration":"230.886615ms","start":"2026-05-09T06:01:51.482158Z","end":"2026-05-09T06:01:51.713045Z","steps":["trace[267376417] 'read index received' (duration: 67.650567ms)","trace[267376417] 'applied index is now lower than readState.Index' (duration: 163.234958ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:51.713229Z","caller":"traceutil/trace.go:171","msg":"trace[486942898] transaction","detail":"{read_only:false; response_revision:24988; number_of_response:1; }","duration":"313.148064ms","start":"2026-05-09T06:01:51.400041Z","end":"2026-05-09T06:01:51.713189Z","steps":["trace[486942898] 'process raft request' (duration: 149.781793ms)","trace[486942898] 'compare' (duration: 162.422691ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:51.713288Z","caller":"traceutil/trace.go:171","msg":"trace[232615300] transaction","detail":"{read_only:false; response_revision:24989; number_of_response:1; }","duration":"311.746764ms","start":"2026-05-09T06:01:51.401527Z","end":"2026-05-09T06:01:51.713274Z","steps":["trace[232615300] 'process raft request' (duration: 311.068309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:51.713393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:51.40152Z","time spent":"311.824435ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T06:01:51.713407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:51.400021Z","time spent":"313.289246ms","remote":"127.0.0.1:39908","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-05-09T06:01:51.71369Z","caller":"traceutil/trace.go:171","msg":"trace[118418935] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"236.480434ms","start":"2026-05-09T06:01:51.477197Z","end":"2026-05-09T06:01:51.713678Z","steps":["trace[118418935] 'process raft request' (duration: 235.469933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:51.713832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.665052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T06:01:51.713876Z","caller":"traceutil/trace.go:171","msg":"trace[1040145379] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:24990; }","duration":"231.736463ms","start":"2026-05-09T06:01:51.482125Z","end":"2026-05-09T06:01:51.713862Z","steps":["trace[1040145379] 'agreement among raft nodes before linearized reading' (duration: 231.655981ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:52.202383Z","caller":"traceutil/trace.go:171","msg":"trace[1653262092] linearizableReadLoop","detail":"{readStateIndex:25805; appliedIndex:25804; }","duration":"292.398444ms","start":"2026-05-09T06:01:51.909956Z","end":"2026-05-09T06:01:52.202355Z","steps":["trace[1653262092] 'read index received' (duration: 292.234411ms)","trace[1653262092] 'applied index is now lower than readState.Index' (duration: 163.413µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:52.202473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.518396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:01:52.202438Z","caller":"traceutil/trace.go:171","msg":"trace[1410444178] transaction","detail":"{read_only:false; response_revision:24991; number_of_response:1; }","duration":"292.6702ms","start":"2026-05-09T06:01:51.90971Z","end":"2026-05-09T06:01:52.20238Z","steps":["trace[1410444178] 'process raft request' (duration: 292.534447ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:52.202496Z","caller":"traceutil/trace.go:171","msg":"trace[1624687188] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24991; }","duration":"292.597848ms","start":"2026-05-09T06:01:51.90989Z","end":"2026-05-09T06:01:52.202488Z","steps":["trace[1624687188] 'agreement among raft nodes before linearized reading' (duration: 292.543907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:52.637762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.042829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:01:52.637865Z","caller":"traceutil/trace.go:171","msg":"trace[1252633843] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24991; }","duration":"199.207513ms","start":"2026-05-09T06:01:52.438631Z","end":"2026-05-09T06:01:52.637839Z","steps":["trace[1252633843] 'count revisions from in-memory index tree' (duration: 198.947537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:52.637937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.814596ms","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-05-09T06:01:52.638046Z","caller":"traceutil/trace.go:171","msg":"trace[1550497815] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24991; }","duration":"224.934959ms","start":"2026-05-09T06:01:52.413093Z","end":"2026-05-09T06:01:52.638028Z","steps":["trace[1550497815] 'range keys from in-memory index tree' (duration: 224.788596ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:52.783677Z","caller":"traceutil/trace.go:171","msg":"trace[987637148] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"119.04237ms","start":"2026-05-09T06:01:52.6646Z","end":"2026-05-09T06:01:52.783642Z","steps":["trace[987637148] 'process raft request' (duration: 118.858836ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:53.874261Z","caller":"traceutil/trace.go:171","msg":"trace[40218242] transaction","detail":"{read_only:false; response_revision:25003; number_of_response:1; }","duration":"152.012081ms","start":"2026-05-09T06:01:53.722218Z","end":"2026-05-09T06:01:53.87423Z","steps":["trace[40218242] 'process raft request' (duration: 151.797876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:54.104238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.444474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T06:01:54.104363Z","caller":"traceutil/trace.go:171","msg":"trace[241504624] linearizableReadLoop","detail":"{readStateIndex:25818; appliedIndex:25816; }","duration":"253.490247ms","start":"2026-05-09T06:01:53.850853Z","end":"2026-05-09T06:01:54.104343Z","steps":["trace[241504624] 'read index received' (duration: 23.131022ms)","trace[241504624] 'applied index is now lower than readState.Index' (duration: 230.357775ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:54.104446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.592149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:01:54.104476Z","caller":"traceutil/trace.go:171","msg":"trace[2050548881] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:25004; }","duration":"253.664791ms","start":"2026-05-09T06:01:53.850799Z","end":"2026-05-09T06:01:54.104464Z","steps":["trace[2050548881] 'agreement among raft nodes before linearized reading' (duration: 253.595979ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:54.104568Z","caller":"traceutil/trace.go:171","msg":"trace[1024110660] transaction","detail":"{read_only:false; response_revision:25004; number_of_response:1; }","duration":"381.000986ms","start":"2026-05-09T06:01:53.723529Z","end":"2026-05-09T06:01:54.10453Z","steps":["trace[1024110660] 'process raft request' (duration: 233.165415ms)","trace[1024110660] 'compare' (duration: 147.29621ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:54.104758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:53.723511Z","time spent":"381.139479ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T06:01:54.337638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.484085ms","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-05-09T06:01:54.337752Z","caller":"traceutil/trace.go:171","msg":"trace[2064557549] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:25004; }","duration":"116.724051ms","start":"2026-05-09T06:01:54.221004Z","end":"2026-05-09T06:01:54.337728Z","steps":["trace[2064557549] 'count revisions from in-memory index tree' (duration: 116.376052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:55.129261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.384518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T06:01:55.129407Z","caller":"traceutil/trace.go:171","msg":"trace[2105011490] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"260.589397ms","start":"2026-05-09T06:01:54.868793Z","end":"2026-05-09T06:01:55.129383Z","steps":["trace[2105011490] 'process raft request' (duration: 114.35864ms)","trace[2105011490] 'compare' (duration: 144.727994ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:55.204175Z","caller":"traceutil/trace.go:171","msg":"trace[696129913] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"334.849344ms","start":"2026-05-09T06:01:54.86931Z","end":"2026-05-09T06:01:55.204159Z","steps":["trace[696129913] 'process raft request' (duration: 334.508647ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:55.204233Z","caller":"traceutil/trace.go:171","msg":"trace[1428595719] transaction","detail":"{read_only:false; response_revision:25008; number_of_response:1; }","duration":"333.936355ms","start":"2026-05-09T06:01:54.870266Z","end":"2026-05-09T06:01:55.204203Z","steps":["trace[1428595719] 'process raft request' (duration: 333.766901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:55.204284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:54.869301Z","time spent":"334.927406ms","remote":"127.0.0.1:39908","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-05-09T06:01:55.204332Z","caller":"traceutil/trace.go:171","msg":"trace[1220990449] linearizableReadLoop","detail":"{readStateIndex:25824; appliedIndex:25820; }","duration":"128.834677ms","start":"2026-05-09T06:01:55.075479Z","end":"2026-05-09T06:01:55.204313Z","steps":["trace[1220990449] 'read index received' (duration: 128.347107ms)","trace[1220990449] 'applied index is now lower than readState.Index' (duration: 484.83µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:55.204411Z","caller":"traceutil/trace.go:171","msg":"trace[730170926] transaction","detail":"{read_only:false; response_revision:25010; number_of_response:1; }","duration":"328.449039ms","start":"2026-05-09T06:01:54.875928Z","end":"2026-05-09T06:01:55.204377Z","steps":["trace[730170926] 'process raft request' (duration: 328.187773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:55.204387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:54.870235Z","time spent":"334.066638ms","remote":"127.0.0.1:39908","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-05-09T06:01:55.204596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:54.875922Z","time spent":"328.576042ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:01:55.204231Z","caller":"traceutil/trace.go:171","msg":"trace[379317261] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"330.439101ms","start":"2026-05-09T06:01:54.873723Z","end":"2026-05-09T06:01:55.204162Z","steps":["trace[379317261] 'process raft request' (duration: 330.352449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:55.204852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:01:54.873716Z","time spent":"331.096326ms","remote":"127.0.0.1:39908","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-05-09T06:01:55.204933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.3887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-09T06:01:55.20506Z","caller":"traceutil/trace.go:171","msg":"trace[622809022] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:25010; }","duration":"129.569073ms","start":"2026-05-09T06:01:55.075465Z","end":"2026-05-09T06:01:55.205034Z","steps":["trace[622809022] 'agreement among raft nodes before linearized reading' (duration: 128.993371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:55.460625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.391027ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-05-09T06:01:55.461138Z","caller":"traceutil/trace.go:171","msg":"trace[56221265] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"251.79876ms","start":"2026-05-09T06:01:55.209326Z","end":"2026-05-09T06:01:55.461125Z","steps":["trace[56221265] 'process raft request' (duration: 251.374341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:55.536037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.535979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-09T06:01:55.536116Z","caller":"traceutil/trace.go:171","msg":"trace[1034269579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25012; }","duration":"126.690332ms","start":"2026-05-09T06:01:55.409408Z","end":"2026-05-09T06:01:55.536098Z","steps":["trace[1034269579] 'agreement among raft nodes before linearized reading' (duration: 126.448117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:55.536047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.962404ms","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-05-09T06:01:55.536226Z","caller":"traceutil/trace.go:171","msg":"trace[158853153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25012; }","duration":"124.201459ms","start":"2026-05-09T06:01:55.412009Z","end":"2026-05-09T06:01:55.53621Z","steps":["trace[158853153] 'agreement among raft nodes before linearized reading' (duration: 123.913113ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:55.535958Z","caller":"traceutil/trace.go:171","msg":"trace[935198373] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"126.477077ms","start":"2026-05-09T06:01:55.409441Z","end":"2026-05-09T06:01:55.535918Z","steps":["trace[935198373] 'process raft request' (duration: 126.262142ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:55.67989Z","caller":"traceutil/trace.go:171","msg":"trace[120280634] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"218.059824ms","start":"2026-05-09T06:01:55.461802Z","end":"2026-05-09T06:01:55.679862Z","steps":["trace[120280634] 'process raft request' (duration: 135.817937ms)","trace[120280634] 'compare' (duration: 81.987862ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:55.680057Z","caller":"traceutil/trace.go:171","msg":"trace[688757289] transaction","detail":"{read_only:false; response_revision:25015; number_of_response:1; }","duration":"214.906875ms","start":"2026-05-09T06:01:55.465116Z","end":"2026-05-09T06:01:55.680023Z","steps":["trace[688757289] 'process raft request' (duration: 214.837444ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:55.680274Z","caller":"traceutil/trace.go:171","msg":"trace[1982128421] transaction","detail":"{read_only:false; response_revision:25014; number_of_response:1; }","duration":"217.596563ms","start":"2026-05-09T06:01:55.462666Z","end":"2026-05-09T06:01:55.680262Z","steps":["trace[1982128421] 'process raft request' (duration: 217.139274ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:55.683017Z","caller":"traceutil/trace.go:171","msg":"trace[1659963782] transaction","detail":"{read_only:false; response_revision:25016; number_of_response:1; }","duration":"142.389874ms","start":"2026-05-09T06:01:55.540607Z","end":"2026-05-09T06:01:55.682997Z","steps":["trace[1659963782] 'process raft request' (duration: 142.093798ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:55.683389Z","caller":"traceutil/trace.go:171","msg":"trace[1430406850] transaction","detail":"{read_only:false; response_revision:25017; number_of_response:1; }","duration":"142.727421ms","start":"2026-05-09T06:01:55.540651Z","end":"2026-05-09T06:01:55.683379Z","steps":["trace[1430406850] 'process raft request' (duration: 142.168ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:56.255969Z","caller":"traceutil/trace.go:171","msg":"trace[755954271] transaction","detail":"{read_only:false; response_revision:25019; number_of_response:1; }","duration":"144.924099ms","start":"2026-05-09T06:01:56.111017Z","end":"2026-05-09T06:01:56.255941Z","steps":["trace[755954271] 'process raft request' (duration: 144.736306ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:57.310157Z","caller":"traceutil/trace.go:171","msg":"trace[903607481] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"101.664789ms","start":"2026-05-09T06:01:57.20846Z","end":"2026-05-09T06:01:57.310125Z","steps":["trace[903607481] 'process raft request' (duration: 101.406864ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:57.310542Z","caller":"traceutil/trace.go:171","msg":"trace[64274438] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"101.329372ms","start":"2026-05-09T06:01:57.209174Z","end":"2026-05-09T06:01:57.310504Z","steps":["trace[64274438] 'process raft request' (duration: 100.864613ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:57.311095Z","caller":"traceutil/trace.go:171","msg":"trace[744102749] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"100.795912ms","start":"2026-05-09T06:01:57.210255Z","end":"2026-05-09T06:01:57.311051Z","steps":["trace[744102749] 'process raft request' (duration: 100.192038ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:01:57.311167Z","caller":"traceutil/trace.go:171","msg":"trace[621162963] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"100.160148ms","start":"2026-05-09T06:01:57.210993Z","end":"2026-05-09T06:01:57.311153Z","steps":["trace[621162963] 'process raft request' (duration: 99.952563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:57.774462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.308339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T06:01:57.774552Z","caller":"traceutil/trace.go:171","msg":"trace[1382021883] linearizableReadLoop","detail":"{readStateIndex:25844; appliedIndex:25843; }","duration":"147.811912ms","start":"2026-05-09T06:01:57.626726Z","end":"2026-05-09T06:01:57.774538Z","steps":["trace[1382021883] 'read index received' (duration: 33.278758ms)","trace[1382021883] 'applied index is now lower than readState.Index' (duration: 114.532073ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:57.774654Z","caller":"traceutil/trace.go:171","msg":"trace[1319499230] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"234.023584ms","start":"2026-05-09T06:01:57.540611Z","end":"2026-05-09T06:01:57.774635Z","steps":["trace[1319499230] 'process raft request' (duration: 119.457619ms)","trace[1319499230] 'compare' (duration: 114.206547ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:01:57.774671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.953084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:01:57.77469Z","caller":"traceutil/trace.go:171","msg":"trace[1189011056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25029; }","duration":"148.005756ms","start":"2026-05-09T06:01:57.626678Z","end":"2026-05-09T06:01:57.774683Z","steps":["trace[1189011056] 'agreement among raft nodes before linearized reading' (duration: 147.936034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:58.017534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.331945ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T06:01:58.017723Z","caller":"traceutil/trace.go:171","msg":"trace[396556876] linearizableReadLoop","detail":"{readStateIndex:25847; appliedIndex:25845; }","duration":"123.248739ms","start":"2026-05-09T06:01:57.894456Z","end":"2026-05-09T06:01:58.017705Z","steps":["trace[396556876] 'read index received' (duration: 32.041µs)","trace[396556876] 'applied index is now lower than readState.Index' (duration: 123.215528ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:58.017753Z","caller":"traceutil/trace.go:171","msg":"trace[677053733] transaction","detail":"{read_only:false; response_revision:25031; number_of_response:1; }","duration":"236.566917ms","start":"2026-05-09T06:01:57.781162Z","end":"2026-05-09T06:01:58.017728Z","steps":["trace[677053733] 'process raft request' (duration: 101.947066ms)","trace[677053733] 'compare' (duration: 133.882855ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:01:58.017779Z","caller":"traceutil/trace.go:171","msg":"trace[271523960] transaction","detail":"{read_only:false; response_revision:25032; number_of_response:1; }","duration":"233.528142ms","start":"2026-05-09T06:01:57.78423Z","end":"2026-05-09T06:01:58.017758Z","steps":["trace[271523960] 'process raft request' (duration: 233.40726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:01:58.017846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.381782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:01:58.018058Z","caller":"traceutil/trace.go:171","msg":"trace[1148395547] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:25032; }","duration":"123.624077ms","start":"2026-05-09T06:01:57.894422Z","end":"2026-05-09T06:01:58.018046Z","steps":["trace[1148395547] 'agreement among raft nodes before linearized reading' (duration: 123.381031ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:02:28.683104Z","caller":"traceutil/trace.go:171","msg":"trace[982201045] transaction","detail":"{read_only:false; response_revision:25407; number_of_response:1; }","duration":"200.769265ms","start":"2026-05-09T06:02:28.482297Z","end":"2026-05-09T06:02:28.683066Z","steps":["trace[982201045] 'process raft request' (duration: 105.555872ms)","trace[982201045] 'compare' (duration: 94.875206ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:02:29.284315Z","caller":"traceutil/trace.go:171","msg":"trace[2002172144] transaction","detail":"{read_only:false; response_revision:25408; number_of_response:1; }","duration":"254.671381ms","start":"2026-05-09T06:02:29.029619Z","end":"2026-05-09T06:02:29.28429Z","steps":["trace[2002172144] 'process raft request' (duration: 254.480807ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:03:53.928449Z","caller":"traceutil/trace.go:171","msg":"trace[402375626] transaction","detail":"{read_only:false; response_revision:26158; number_of_response:1; }","duration":"145.020602ms","start":"2026-05-09T06:03:53.783398Z","end":"2026-05-09T06:03:53.928418Z","steps":["trace[402375626] 'process raft request' (duration: 59.075768ms)","trace[402375626] 'compare' (duration: 85.600767ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:03:53.928874Z","caller":"traceutil/trace.go:171","msg":"trace[319090937] transaction","detail":"{read_only:false; response_revision:26160; number_of_response:1; }","duration":"143.188612ms","start":"2026-05-09T06:03:53.785677Z","end":"2026-05-09T06:03:53.928866Z","steps":["trace[319090937] 'process raft request' (duration: 143.041239ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:03:53.928868Z","caller":"traceutil/trace.go:171","msg":"trace[460133599] transaction","detail":"{read_only:false; response_revision:26159; number_of_response:1; }","duration":"144.289036ms","start":"2026-05-09T06:03:53.784551Z","end":"2026-05-09T06:03:53.92884Z","steps":["trace[460133599] 'process raft request' (duration: 143.785295ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:03:53.929032Z","caller":"traceutil/trace.go:171","msg":"trace[374669780] transaction","detail":"{read_only:false; response_revision:26161; number_of_response:1; }","duration":"142.254442ms","start":"2026-05-09T06:03:53.786748Z","end":"2026-05-09T06:03:53.929002Z","steps":["trace[374669780] 'process raft request' (duration: 142.087478ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:03:57.839077Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069a3.wal"} {"level":"info","ts":"2026-05-09T06:04:01.547951Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23272} {"level":"info","ts":"2026-05-09T06:04:01.700756Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23272,"took":"147.869321ms","hash":1511491627,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":36818944,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-09T06:04:01.700973Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1511491627,"revision":23272,"compact-revision":20034} {"level":"info","ts":"2026-05-09T06:09:01.554384Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26239} {"level":"info","ts":"2026-05-09T06:09:01.699481Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26239,"took":"140.271434ms","hash":1819878062,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":33275904,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-09T06:09:01.699569Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1819878062,"revision":26239,"compact-revision":23272} {"level":"info","ts":"2026-05-09T06:09:33.249992Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"56c01ea8fdc90390","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T06:09:33.255261Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-09T06:09:33.255402Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-09T06:14:01.560744Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28847} {"level":"info","ts":"2026-05-09T06:14:01.709193Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28847,"took":"143.876783ms","hash":4168507848,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":30027776,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T06:14:01.709273Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4168507848,"revision":28847,"compact-revision":26239} {"level":"info","ts":"2026-05-09T06:19:01.567047Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31064} {"level":"info","ts":"2026-05-09T06:19:01.68236Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31064,"took":"110.949695ms","hash":3204798338,"current-db-size-bytes":58773504,"current-db-size":"59 MB","current-db-size-in-use-bytes":31145984,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-09T06:19:01.682433Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3204798338,"revision":31064,"compact-revision":28847} {"level":"info","ts":"2026-05-09T06:24:01.574245Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33365} {"level":"info","ts":"2026-05-09T06:24:01.722067Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33365,"took":"143.329506ms","hash":963506100,"current-db-size-bytes":60669952,"current-db-size":"61 MB","current-db-size-in-use-bytes":32403456,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T06:24:01.722218Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":963506100,"revision":33365,"compact-revision":31064} {"level":"info","ts":"2026-05-09T06:29:01.579706Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35625} {"level":"info","ts":"2026-05-09T06:29:01.720547Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35625,"took":"136.984563ms","hash":784314341,"current-db-size-bytes":62394368,"current-db-size":"62 MB","current-db-size-in-use-bytes":30965760,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-09T06:29:01.720627Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":784314341,"revision":35625,"compact-revision":33365}