{"level":"warn","ts":"2026-05-28T21:45:18.379445Z","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-28T21:45:18.379531Z","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-28T21:45:18.379587Z","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-28T21:45:18.379598Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-05-28T21:45:18.379621Z","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-28T21:45:18.380214Z","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-28T21:45:18.38028Z","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-28T21:45:18.383984Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.471568ms"} {"level":"info","ts":"2026-05-28T21:45:18.397298Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-05-28T21:45:18.397409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-05-28T21:45:18.397449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-05-28T21:45:18.397469Z","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-28T21:45:18.397492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-05-28T21:45:18.397543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-05-28T21:45:18.404265Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-28T21:45:18.408091Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-28T21:45:18.409635Z","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-28T21:45:18.412623Z","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-28T21:45:18.41285Z","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-28T21:45:18.412918Z","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-28T21:45:18.413024Z","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-28T21:45:18.413044Z","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-28T21:45:18.414222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-05-28T21:45:18.414432Z","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-28T21:45:18.416637Z","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-28T21:45:18.416759Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-28T21:45:18.416855Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-05-28T21:45:18.417005Z","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-28T21:45:18.417082Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-28T21:45:19.198169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-05-28T21:45:19.198275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-28T21:45:19.19832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-05-28T21:45:19.19834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-05-28T21:45:19.198351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-28T21:45:19.198367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-05-28T21:45:19.198448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-05-28T21:45:19.199561Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:45:19.200551Z","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-28T21:45:19.200554Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T21:45:19.200676Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T21:45:19.200951Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-28T21:45:19.201061Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-28T21:45:19.201076Z","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-28T21:45:19.201385Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:45:19.201434Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:45:19.204405Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-28T21:45:19.205064Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"warn","ts":"2026-05-28T21:45:27.64912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.882213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:45:27.649257Z","caller":"traceutil/trace.go:171","msg":"trace[1858753917] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:0; response_revision:334; }","duration":"172.114138ms","start":"2026-05-28T21:45:27.477118Z","end":"2026-05-28T21:45:27.649232Z","steps":["trace[1858753917] 'range keys from in-memory index tree' (duration: 171.804372ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:27.939187Z","caller":"traceutil/trace.go:171","msg":"trace[275742009] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"104.122912ms","start":"2026-05-28T21:45:27.835041Z","end":"2026-05-28T21:45:27.939164Z","steps":["trace[275742009] 'process raft request' (duration: 95.80627ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:55.559805Z","caller":"traceutil/trace.go:171","msg":"trace[1354012480] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"101.386798ms","start":"2026-05-28T21:45:55.458383Z","end":"2026-05-28T21:45:55.55977Z","steps":["trace[1354012480] 'process raft request' (duration: 74.769839ms)","trace[1354012480] 'compare' (duration: 26.289813ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:46:01.905162Z","caller":"traceutil/trace.go:171","msg":"trace[1799481432] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"127.560679ms","start":"2026-05-28T21:46:01.777571Z","end":"2026-05-28T21:46:01.905132Z","steps":["trace[1799481432] 'process raft request' (duration: 98.214847ms)","trace[1799481432] 'compare' (duration: 29.20766ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:46:02.165128Z","caller":"traceutil/trace.go:171","msg":"trace[1656710223] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"243.932781ms","start":"2026-05-28T21:46:01.921166Z","end":"2026-05-28T21:46:02.165098Z","steps":["trace[1656710223] 'process raft request' (duration: 243.780258ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:02.212748Z","caller":"traceutil/trace.go:171","msg":"trace[1644165136] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:659; }","duration":"202.683509ms","start":"2026-05-28T21:46:02.010018Z","end":"2026-05-28T21:46:02.212702Z","steps":["trace[1644165136] 'read index received' (duration: 155.007727ms)","trace[1644165136] 'applied index is now lower than readState.Index' (duration: 47.675122ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:02.212899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.863042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T21:46:02.212929Z","caller":"traceutil/trace.go:171","msg":"trace[955366146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"202.985705ms","start":"2026-05-28T21:46:02.009933Z","end":"2026-05-28T21:46:02.212919Z","steps":["trace[955366146] 'agreement among raft nodes before linearized reading' (duration: 202.903733ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:02.212909Z","caller":"traceutil/trace.go:171","msg":"trace[329676051] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"225.273189ms","start":"2026-05-28T21:46:01.987586Z","end":"2026-05-28T21:46:02.212859Z","steps":["trace[329676051] 'process raft request' (duration: 224.441143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:02.21298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.192833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:46:02.213026Z","caller":"traceutil/trace.go:171","msg":"trace[1419250408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"198.271754ms","start":"2026-05-28T21:46:02.014737Z","end":"2026-05-28T21:46:02.213009Z","steps":["trace[1419250408] 'agreement among raft nodes before linearized reading' (duration: 198.177783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:02.717583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.573697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:46:02.717648Z","caller":"traceutil/trace.go:171","msg":"trace[235572168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"503.7013ms","start":"2026-05-28T21:46:02.213934Z","end":"2026-05-28T21:46:02.717636Z","steps":["trace[235572168] 'range keys from in-memory index tree' (duration: 503.517925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:02.717676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:02.213924Z","time spent":"503.74746ms","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T21:46:02.722861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.88183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:46:02.723022Z","caller":"traceutil/trace.go:171","msg":"trace[1996056499] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"432.556259ms","start":"2026-05-28T21:46:02.290456Z","end":"2026-05-28T21:46:02.723012Z","steps":["trace[1996056499] 'process raft request' (duration: 432.489108ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:02.723015Z","caller":"traceutil/trace.go:171","msg":"trace[997592657] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"500.889585ms","start":"2026-05-28T21:46:02.222089Z","end":"2026-05-28T21:46:02.722979Z","steps":["trace[997592657] 'process raft request' (duration: 137.899028ms)","trace[997592657] 'compare' (duration: 357.443411ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:02.723066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:02.290445Z","time spent":"432.60587ms","remote":"127.0.0.1:43624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T21:46:02.723144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:02.222067Z","time spent":"501.008228ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:46:02.723169Z","caller":"traceutil/trace.go:171","msg":"trace[160960840] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"466.538519ms","start":"2026-05-28T21:46:02.256569Z","end":"2026-05-28T21:46:02.723107Z","steps":["trace[160960840] 'read index received' (duration: 103.359968ms)","trace[160960840] 'applied index is now lower than readState.Index' (duration: 363.172951ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:02.72341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.175536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-28T21:46:02.723375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.793744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-28T21:46:02.723579Z","caller":"traceutil/trace.go:171","msg":"trace[1778530818] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:640; }","duration":"467.029589ms","start":"2026-05-28T21:46:02.256528Z","end":"2026-05-28T21:46:02.723558Z","steps":["trace[1778530818] 'agreement among raft nodes before linearized reading' (duration: 466.699382ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:02.723475Z","caller":"traceutil/trace.go:171","msg":"trace[566977550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"264.257358ms","start":"2026-05-28T21:46:02.459198Z","end":"2026-05-28T21:46:02.723455Z","steps":["trace[566977550] 'agreement among raft nodes before linearized reading' (duration: 264.105445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:02.723633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:02.25651Z","time spent":"467.1079ms","remote":"127.0.0.1:43394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.132\" "} {"level":"info","ts":"2026-05-28T21:46:17.242003Z","caller":"traceutil/trace.go:171","msg":"trace[1272197415] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"226.976914ms","start":"2026-05-28T21:46:17.01499Z","end":"2026-05-28T21:46:17.241967Z","steps":["trace[1272197415] 'read index received' (duration: 226.682919ms)","trace[1272197415] 'applied index is now lower than readState.Index' (duration: 292.825µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:46:17.242047Z","caller":"traceutil/trace.go:171","msg":"trace[404216617] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"244.608242ms","start":"2026-05-28T21:46:16.997407Z","end":"2026-05-28T21:46:17.242015Z","steps":["trace[404216617] 'process raft request' (duration: 244.357207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:17.242235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.210908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:46:17.242447Z","caller":"traceutil/trace.go:171","msg":"trace[673240583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"227.477834ms","start":"2026-05-28T21:46:17.014952Z","end":"2026-05-28T21:46:17.24243Z","steps":["trace[673240583] 'agreement among raft nodes before linearized reading' (duration: 227.206459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:17.242452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.097292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-28T21:46:17.242549Z","caller":"traceutil/trace.go:171","msg":"trace[184640048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"185.241845ms","start":"2026-05-28T21:46:17.057286Z","end":"2026-05-28T21:46:17.242528Z","steps":["trace[184640048] 'agreement among raft nodes before linearized reading' (duration: 184.933279ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:30.577903Z","caller":"traceutil/trace.go:171","msg":"trace[505669099] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"140.737615ms","start":"2026-05-28T21:47:30.43712Z","end":"2026-05-28T21:47:30.577858Z","steps":["trace[505669099] 'process raft request' (duration: 120.441143ms)","trace[505669099] 'compare' (duration: 20.154769ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:30.851633Z","caller":"traceutil/trace.go:171","msg":"trace[1223255474] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1932; }","duration":"189.50069ms","start":"2026-05-28T21:47:30.662103Z","end":"2026-05-28T21:47:30.851604Z","steps":["trace[1223255474] 'read index received' (duration: 181.418329ms)","trace[1223255474] 'applied index is now lower than readState.Index' (duration: 8.081321ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:47:30.851734Z","caller":"traceutil/trace.go:171","msg":"trace[479769710] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"224.154442ms","start":"2026-05-28T21:47:30.62753Z","end":"2026-05-28T21:47:30.851684Z","steps":["trace[479769710] 'process raft request' (duration: 215.970849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:30.851862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.739654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-879dde35-de33-496b-b62e-d9c0931c7c51\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-05-28T21:47:30.851753Z","caller":"traceutil/trace.go:171","msg":"trace[1647775116] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"222.815186ms","start":"2026-05-28T21:47:30.628901Z","end":"2026-05-28T21:47:30.851716Z","steps":["trace[1647775116] 'process raft request' (duration: 222.660954ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:30.851909Z","caller":"traceutil/trace.go:171","msg":"trace[2035139372] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-879dde35-de33-496b-b62e-d9c0931c7c51; range_end:; response_count:1; response_revision:1863; }","duration":"189.839618ms","start":"2026-05-28T21:47:30.662058Z","end":"2026-05-28T21:47:30.851897Z","steps":["trace[2035139372] 'agreement among raft nodes before linearized reading' (duration: 189.673564ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:30.85186Z","caller":"traceutil/trace.go:171","msg":"trace[741130215] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"223.480109ms","start":"2026-05-28T21:47:30.628322Z","end":"2026-05-28T21:47:30.851802Z","steps":["trace[741130215] 'process raft request' (duration: 223.191174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:48:15.343573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.914388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:48:15.343685Z","caller":"traceutil/trace.go:171","msg":"trace[536544353] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2153; }","duration":"125.116311ms","start":"2026-05-28T21:48:15.218547Z","end":"2026-05-28T21:48:15.343663Z","steps":["trace[536544353] 'count revisions from in-memory index tree' (duration: 124.786905ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:48:15.683161Z","caller":"traceutil/trace.go:171","msg":"trace[1151184887] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"196.709377ms","start":"2026-05-28T21:48:15.486423Z","end":"2026-05-28T21:48:15.683132Z","steps":["trace[1151184887] 'process raft request' (duration: 145.848637ms)","trace[1151184887] 'compare' (duration: 50.622386ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:49:43.455072Z","caller":"traceutil/trace.go:171","msg":"trace[1077645247] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"195.464378ms","start":"2026-05-28T21:49:43.259585Z","end":"2026-05-28T21:49:43.455049Z","steps":["trace[1077645247] 'process raft request' (duration: 195.315575ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:31.477701Z","caller":"traceutil/trace.go:171","msg":"trace[1794174742] transaction","detail":"{read_only:false; response_revision:4834; number_of_response:1; }","duration":"100.364577ms","start":"2026-05-28T21:53:31.377299Z","end":"2026-05-28T21:53:31.477663Z","steps":["trace[1794174742] 'process raft request' (duration: 99.590752ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:36.128331Z","caller":"traceutil/trace.go:171","msg":"trace[617261971] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"166.740895ms","start":"2026-05-28T21:53:35.961561Z","end":"2026-05-28T21:53:36.128302Z","steps":["trace[617261971] 'process raft request' (duration: 142.885678ms)","trace[617261971] 'compare' (duration: 23.722883ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:53:36.128618Z","caller":"traceutil/trace.go:171","msg":"trace[605658688] linearizableReadLoop","detail":"{readStateIndex:5169; appliedIndex:5168; }","duration":"165.180974ms","start":"2026-05-28T21:53:35.963421Z","end":"2026-05-28T21:53:36.128602Z","steps":["trace[605658688] 'read index received' (duration: 141.024032ms)","trace[605658688] 'applied index is now lower than readState.Index' (duration: 24.154692ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:53:36.128828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.408458ms","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-28T21:53:36.128826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.792625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:53:36.128849Z","caller":"traceutil/trace.go:171","msg":"trace[335621457] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4980; }","duration":"165.464139ms","start":"2026-05-28T21:53:35.963379Z","end":"2026-05-28T21:53:36.128843Z","steps":["trace[335621457] 'agreement among raft nodes before linearized reading' (duration: 165.310527ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:36.128865Z","caller":"traceutil/trace.go:171","msg":"trace[145386872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4980; }","duration":"113.882967ms","start":"2026-05-28T21:53:36.014972Z","end":"2026-05-28T21:53:36.128855Z","steps":["trace[145386872] 'agreement among raft nodes before linearized reading' (duration: 113.805435ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:36.324631Z","caller":"traceutil/trace.go:171","msg":"trace[611065769] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"190.060119ms","start":"2026-05-28T21:53:36.134534Z","end":"2026-05-28T21:53:36.324594Z","steps":["trace[611065769] 'process raft request' (duration: 131.390004ms)","trace[611065769] 'compare' (duration: 58.432351ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:53:36.32474Z","caller":"traceutil/trace.go:171","msg":"trace[1916820953] linearizableReadLoop","detail":"{readStateIndex:5170; appliedIndex:5169; }","duration":"179.45782ms","start":"2026-05-28T21:53:36.145241Z","end":"2026-05-28T21:53:36.324699Z","steps":["trace[1916820953] 'read index received' (duration: 120.679493ms)","trace[1916820953] 'applied index is now lower than readState.Index' (duration: 58.776357ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:53:36.325007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.747476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-05-28T21:53:36.325036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.060268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f32904ea-71a0-4885-9b76-eab4292ab0bb\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-28T21:53:36.325051Z","caller":"traceutil/trace.go:171","msg":"trace[858765046] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4982; }","duration":"179.822197ms","start":"2026-05-28T21:53:36.145217Z","end":"2026-05-28T21:53:36.325039Z","steps":["trace[858765046] 'agreement among raft nodes before linearized reading' (duration: 179.619993ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:36.325081Z","caller":"traceutil/trace.go:171","msg":"trace[937970179] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f32904ea-71a0-4885-9b76-eab4292ab0bb; range_end:; response_count:1; response_revision:4982; }","duration":"160.139659ms","start":"2026-05-28T21:53:36.164929Z","end":"2026-05-28T21:53:36.325069Z","steps":["trace[937970179] 'agreement among raft nodes before linearized reading' (duration: 160.017657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:36.325136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.026865ms","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-28T21:53:36.325181Z","caller":"traceutil/trace.go:171","msg":"trace[971403631] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4982; }","duration":"131.098218ms","start":"2026-05-28T21:53:36.194072Z","end":"2026-05-28T21:53:36.32517Z","steps":["trace[971403631] 'agreement among raft nodes before linearized reading' (duration: 131.025797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:36.325164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.7084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:53:36.325247Z","caller":"traceutil/trace.go:171","msg":"trace[1776657547] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:4982; }","duration":"153.820062ms","start":"2026-05-28T21:53:36.171407Z","end":"2026-05-28T21:53:36.325227Z","steps":["trace[1776657547] 'agreement among raft nodes before linearized reading' (duration: 153.6942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:36.325606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.447344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f32904ea-71a0-4885-9b76-eab4292ab0bb\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-28T21:53:36.32577Z","caller":"traceutil/trace.go:171","msg":"trace[695731921] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f32904ea-71a0-4885-9b76-eab4292ab0bb; range_end:; response_count:1; response_revision:4982; }","duration":"101.651668ms","start":"2026-05-28T21:53:36.224099Z","end":"2026-05-28T21:53:36.32575Z","steps":["trace[695731921] 'agreement among raft nodes before linearized reading' (duration: 101.047256ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:36.633758Z","caller":"traceutil/trace.go:171","msg":"trace[596877484] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"174.526627ms","start":"2026-05-28T21:53:36.459186Z","end":"2026-05-28T21:53:36.633713Z","steps":["trace[596877484] 'process raft request' (duration: 166.547889ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:36.77283Z","caller":"traceutil/trace.go:171","msg":"trace[1081393858] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"130.852134ms","start":"2026-05-28T21:53:36.641951Z","end":"2026-05-28T21:53:36.772803Z","steps":["trace[1081393858] 'process raft request' (duration: 130.684501ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:36.772836Z","caller":"traceutil/trace.go:171","msg":"trace[1411951053] transaction","detail":"{read_only:false; response_revision:4995; number_of_response:1; }","duration":"132.561335ms","start":"2026-05-28T21:53:36.640253Z","end":"2026-05-28T21:53:36.772814Z","steps":["trace[1411951053] 'process raft request' (duration: 120.680333ms)","trace[1411951053] 'compare' (duration: 11.569156ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:53:36.772722Z","caller":"traceutil/trace.go:171","msg":"trace[468886387] linearizableReadLoop","detail":"{readStateIndex:5185; appliedIndex:5182; }","duration":"106.351346ms","start":"2026-05-28T21:53:36.666347Z","end":"2026-05-28T21:53:36.772699Z","steps":["trace[468886387] 'read index received' (duration: 5.652526ms)","trace[468886387] 'applied index is now lower than readState.Index' (duration: 100.69787ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:53:36.773386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.015897ms","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-28T21:53:36.77349Z","caller":"traceutil/trace.go:171","msg":"trace[876495819] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4996; }","duration":"107.177641ms","start":"2026-05-28T21:53:36.666288Z","end":"2026-05-28T21:53:36.773466Z","steps":["trace[876495819] 'agreement among raft nodes before linearized reading' (duration: 106.864935ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:55:19.225803Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3019} {"level":"info","ts":"2026-05-28T21:55:19.314655Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3019,"took":"87.230963ms","hash":298046850,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":32034816,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-28T21:55:19.31473Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":298046850,"revision":3019,"compact-revision":-1} {"level":"warn","ts":"2026-05-28T21:59:59.81628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.773828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sv7dm\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-05-28T21:59:59.816392Z","caller":"traceutil/trace.go:171","msg":"trace[1364489855] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sv7dm; range_end:; response_count:1; response_revision:8221; }","duration":"150.932831ms","start":"2026-05-28T21:59:59.665441Z","end":"2026-05-28T21:59:59.816373Z","steps":["trace[1364489855] 'range keys from in-memory index tree' (duration: 150.593505ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:11.667885Z","caller":"traceutil/trace.go:171","msg":"trace[1872484662] linearizableReadLoop","detail":"{readStateIndex:8577; appliedIndex:8576; }","duration":"289.304137ms","start":"2026-05-28T22:00:11.378553Z","end":"2026-05-28T22:00:11.667857Z","steps":["trace[1872484662] 'read index received' (duration: 229.626619ms)","trace[1872484662] 'applied index is now lower than readState.Index' (duration: 59.676708ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:00:11.668118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.557171ms","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-28T22:00:11.66815Z","caller":"traceutil/trace.go:171","msg":"trace[420984490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8285; }","duration":"289.634773ms","start":"2026-05-28T22:00:11.378507Z","end":"2026-05-28T22:00:11.668142Z","steps":["trace[420984490] 'agreement among raft nodes before linearized reading' (duration: 289.45735ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:11.668261Z","caller":"traceutil/trace.go:171","msg":"trace[949022342] transaction","detail":"{read_only:false; response_revision:8285; number_of_response:1; }","duration":"321.359342ms","start":"2026-05-28T22:00:11.34689Z","end":"2026-05-28T22:00:11.668249Z","steps":["trace[949022342] 'process raft request' (duration: 261.343048ms)","trace[949022342] 'compare' (duration: 59.357162ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:00:11.668369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:00:11.346871Z","time spent":"321.419693ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:00:19.233619Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6152} {"level":"info","ts":"2026-05-28T22:00:19.335609Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6152,"took":"99.868466ms","hash":1207449731,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":23359488,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-28T22:00:19.335797Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1207449731,"revision":6152,"compact-revision":3019} {"level":"warn","ts":"2026-05-28T22:00:39.47106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.635157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T22:00:39.471171Z","caller":"traceutil/trace.go:171","msg":"trace[1199505131] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:8483; }","duration":"167.80284ms","start":"2026-05-28T22:00:39.303342Z","end":"2026-05-28T22:00:39.471145Z","steps":["trace[1199505131] 'count revisions from in-memory index tree' (duration: 167.553695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:39.471535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.131371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-7b6985c48d-bdhnz.18b3d9a9610f69b6\" ","response":"range_response_count:1 size:1135"} {"level":"warn","ts":"2026-05-28T22:00:39.471527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.032675ms","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-28T22:00:39.471587Z","caller":"traceutil/trace.go:171","msg":"trace[843153103] range","detail":"{range_begin:/registry/events/openstack/barbican-api-7b6985c48d-bdhnz.18b3d9a9610f69b6; range_end:; response_count:1; response_revision:8483; }","duration":"227.210643ms","start":"2026-05-28T22:00:39.244362Z","end":"2026-05-28T22:00:39.471572Z","steps":["trace[843153103] 'range keys from in-memory index tree' (duration: 227.024639ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:39.471612Z","caller":"traceutil/trace.go:171","msg":"trace[1844560493] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8483; }","duration":"240.173198ms","start":"2026-05-28T22:00:39.231415Z","end":"2026-05-28T22:00:39.471589Z","steps":["trace[1844560493] 'range keys from in-memory index tree' (duration: 239.919694ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:39.620798Z","caller":"traceutil/trace.go:171","msg":"trace[849419653] linearizableReadLoop","detail":"{readStateIndex:8785; appliedIndex:8784; }","duration":"144.834136ms","start":"2026-05-28T22:00:39.475939Z","end":"2026-05-28T22:00:39.620773Z","steps":["trace[849419653] 'read index received' (duration: 144.409728ms)","trace[849419653] 'applied index is now lower than readState.Index' (duration: 423.578µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:00:39.621066Z","caller":"traceutil/trace.go:171","msg":"trace[1388006968] transaction","detail":"{read_only:false; response_revision:8484; number_of_response:1; }","duration":"145.596551ms","start":"2026-05-28T22:00:39.475457Z","end":"2026-05-28T22:00:39.621054Z","steps":["trace[1388006968] 'process raft request' (duration: 145.123502ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:39.621231Z","caller":"traceutil/trace.go:171","msg":"trace[1874241326] transaction","detail":"{read_only:false; response_revision:8486; number_of_response:1; }","duration":"143.078373ms","start":"2026-05-28T22:00:39.47814Z","end":"2026-05-28T22:00:39.621219Z","steps":["trace[1874241326] 'process raft request' (duration: 142.811398ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:39.621058Z","caller":"traceutil/trace.go:171","msg":"trace[1162541503] transaction","detail":"{read_only:false; response_revision:8487; number_of_response:1; }","duration":"142.554354ms","start":"2026-05-28T22:00:39.47848Z","end":"2026-05-28T22:00:39.621035Z","steps":["trace[1162541503] 'process raft request' (duration: 142.510363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:39.621494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.51436ms","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-28T22:00:39.621625Z","caller":"traceutil/trace.go:171","msg":"trace[1710524250] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8487; }","duration":"145.641122ms","start":"2026-05-28T22:00:39.475914Z","end":"2026-05-28T22:00:39.621555Z","steps":["trace[1710524250] 'agreement among raft nodes before linearized reading' (duration: 145.243115ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:39.625395Z","caller":"traceutil/trace.go:171","msg":"trace[277972321] transaction","detail":"{read_only:false; response_revision:8485; number_of_response:1; }","duration":"147.486957ms","start":"2026-05-28T22:00:39.477872Z","end":"2026-05-28T22:00:39.625359Z","steps":["trace[277972321] 'process raft request' (duration: 142.989502ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:41.913886Z","caller":"traceutil/trace.go:171","msg":"trace[135470862] transaction","detail":"{read_only:false; response_revision:8501; number_of_response:1; }","duration":"148.309992ms","start":"2026-05-28T22:00:41.765546Z","end":"2026-05-28T22:00:41.913856Z","steps":["trace[135470862] 'process raft request' (duration: 148.082538ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:42.066408Z","caller":"traceutil/trace.go:171","msg":"trace[1861397455] transaction","detail":"{read_only:false; response_revision:8502; number_of_response:1; }","duration":"148.386774ms","start":"2026-05-28T22:00:41.917987Z","end":"2026-05-28T22:00:42.066374Z","steps":["trace[1861397455] 'process raft request' (duration: 50.571016ms)","trace[1861397455] 'compare' (duration: 97.561764ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:00:51.75881Z","caller":"traceutil/trace.go:171","msg":"trace[1575098068] linearizableReadLoop","detail":"{readStateIndex:8894; appliedIndex:8893; }","duration":"125.011443ms","start":"2026-05-28T22:00:51.633773Z","end":"2026-05-28T22:00:51.758784Z","steps":["trace[1575098068] 'read index received' (duration: 117.156964ms)","trace[1575098068] 'applied index is now lower than readState.Index' (duration: 7.853389ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:00:51.758946Z","caller":"traceutil/trace.go:171","msg":"trace[134078979] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8589; }","duration":"139.827482ms","start":"2026-05-28T22:00:51.6191Z","end":"2026-05-28T22:00:51.758927Z","steps":["trace[134078979] 'process raft request' (duration: 131.874341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:51.759269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.473541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-ctm92\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-05-28T22:00:51.759346Z","caller":"traceutil/trace.go:171","msg":"trace[967561968] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-ctm92; range_end:; response_count:1; response_revision:8589; }","duration":"125.596323ms","start":"2026-05-28T22:00:51.633732Z","end":"2026-05-28T22:00:51.759329Z","steps":["trace[967561968] 'agreement among raft nodes before linearized reading' (duration: 125.362519ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:52.20754Z","caller":"traceutil/trace.go:171","msg":"trace[2114567005] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"108.755526ms","start":"2026-05-28T22:00:52.098762Z","end":"2026-05-28T22:00:52.207517Z","steps":["trace[2114567005] 'process raft request' (duration: 108.46809ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:52.524286Z","caller":"traceutil/trace.go:171","msg":"trace[1319800184] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"116.380439ms","start":"2026-05-28T22:00:52.407876Z","end":"2026-05-28T22:00:52.524257Z","steps":["trace[1319800184] 'process raft request' (duration: 80.026172ms)","trace[1319800184] 'compare' (duration: 36.184224ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:00:53.265504Z","caller":"traceutil/trace.go:171","msg":"trace[596656821] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"139.016688ms","start":"2026-05-28T22:00:53.126434Z","end":"2026-05-28T22:00:53.265451Z","steps":["trace[596656821] 'process raft request' (duration: 138.805414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:53.456846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.955946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-05-28T22:00:53.456915Z","caller":"traceutil/trace.go:171","msg":"trace[1375792533] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:8605; }","duration":"102.042808ms","start":"2026-05-28T22:00:53.354856Z","end":"2026-05-28T22:00:53.456899Z","steps":["trace[1375792533] 'range keys from in-memory index tree' (duration: 101.68438ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:01:41.397002Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002481.wal"} {"level":"info","ts":"2026-05-28T22:02:06.282084Z","caller":"traceutil/trace.go:171","msg":"trace[1538312507] transaction","detail":"{read_only:false; response_revision:9333; number_of_response:1; }","duration":"107.657682ms","start":"2026-05-28T22:02:06.174403Z","end":"2026-05-28T22:02:06.28206Z","steps":["trace[1538312507] 'process raft request' (duration: 107.614892ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:02:06.28212Z","caller":"traceutil/trace.go:171","msg":"trace[1780733572] transaction","detail":"{read_only:false; response_revision:9331; number_of_response:1; }","duration":"109.960416ms","start":"2026-05-28T22:02:06.172119Z","end":"2026-05-28T22:02:06.282079Z","steps":["trace[1780733572] 'process raft request' (duration: 72.409404ms)","trace[1780733572] 'compare' (duration: 37.327218ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:02:06.28228Z","caller":"traceutil/trace.go:171","msg":"trace[1045693452] transaction","detail":"{read_only:false; response_revision:9332; number_of_response:1; }","duration":"109.484808ms","start":"2026-05-28T22:02:06.172782Z","end":"2026-05-28T22:02:06.282266Z","steps":["trace[1045693452] 'process raft request' (duration: 109.191812ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:02:52.500302Z","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-28T22:02:52.505829Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-28T22:02:52.505912Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-28T22:05:19.241057Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8328} {"level":"info","ts":"2026-05-28T22:05:19.327009Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8328,"took":"82.132886ms","hash":2665014942,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":26464256,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-28T22:05:19.327086Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2665014942,"revision":8328,"compact-revision":6152} {"level":"warn","ts":"2026-05-28T22:05:31.457111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.401276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T22:05:31.457252Z","caller":"traceutil/trace.go:171","msg":"trace[1833181908] transaction","detail":"{read_only:false; response_revision:11081; number_of_response:1; }","duration":"159.526611ms","start":"2026-05-28T22:05:31.297695Z","end":"2026-05-28T22:05:31.457221Z","steps":["trace[1833181908] 'process raft request' (duration: 44.894831ms)","trace[1833181908] 'compare' (duration: 114.193602ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:05:31.467527Z","caller":"traceutil/trace.go:171","msg":"trace[1231346721] transaction","detail":"{read_only:false; response_revision:11082; number_of_response:1; }","duration":"168.708858ms","start":"2026-05-28T22:05:31.298786Z","end":"2026-05-28T22:05:31.467495Z","steps":["trace[1231346721] 'process raft request' (duration: 168.429672ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:05:31.467414Z","caller":"traceutil/trace.go:171","msg":"trace[712019263] linearizableReadLoop","detail":"{readStateIndex:11460; appliedIndex:11458; }","duration":"142.29907ms","start":"2026-05-28T22:05:31.3251Z","end":"2026-05-28T22:05:31.467399Z","steps":["trace[712019263] 'read index received' (duration: 17.478455ms)","trace[712019263] 'applied index is now lower than readState.Index' (duration: 124.819695ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:05:31.467714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.589907ms","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-28T22:05:31.4678Z","caller":"traceutil/trace.go:171","msg":"trace[1348202091] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11082; }","duration":"142.73185ms","start":"2026-05-28T22:05:31.325048Z","end":"2026-05-28T22:05:31.46778Z","steps":["trace[1348202091] 'agreement among raft nodes before linearized reading' (duration: 142.506595ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:10:19.247476Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10980} {"level":"info","ts":"2026-05-28T22:10:19.331846Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10980,"took":"81.58914ms","hash":935798319,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":27967488,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-28T22:10:19.331906Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":935798319,"revision":10980,"compact-revision":8328} {"level":"info","ts":"2026-05-28T22:12:15.85199Z","caller":"traceutil/trace.go:171","msg":"trace[2030114951] transaction","detail":"{read_only:false; response_revision:13781; number_of_response:1; }","duration":"368.369445ms","start":"2026-05-28T22:12:15.483594Z","end":"2026-05-28T22:12:15.851963Z","steps":["trace[2030114951] 'process raft request' (duration: 368.203372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:12:15.852636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:12:15.483562Z","time spent":"368.932046ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:12:15.8533Z","caller":"traceutil/trace.go:171","msg":"trace[709360666] linearizableReadLoop","detail":"{readStateIndex:14255; appliedIndex:14253; }","duration":"199.690901ms","start":"2026-05-28T22:12:15.653537Z","end":"2026-05-28T22:12:15.853228Z","steps":["trace[709360666] 'read index received' (duration: 198.276484ms)","trace[709360666] 'applied index is now lower than readState.Index' (duration: 1.412177ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:12:15.853773Z","caller":"traceutil/trace.go:171","msg":"trace[755093031] transaction","detail":"{read_only:false; response_revision:13782; number_of_response:1; }","duration":"248.19077ms","start":"2026-05-28T22:12:15.605543Z","end":"2026-05-28T22:12:15.853734Z","steps":["trace[755093031] 'process raft request' (duration: 247.406646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:12:15.85406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.511036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sv7dm\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-05-28T22:12:15.854187Z","caller":"traceutil/trace.go:171","msg":"trace[1903182252] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sv7dm; range_end:; response_count:1; response_revision:13782; }","duration":"200.66149ms","start":"2026-05-28T22:12:15.653502Z","end":"2026-05-28T22:12:15.854164Z","steps":["trace[1903182252] 'agreement among raft nodes before linearized reading' (duration: 200.421755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:12:15.854332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.518049ms","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-28T22:12:15.854391Z","caller":"traceutil/trace.go:171","msg":"trace[1556647511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13782; }","duration":"137.61268ms","start":"2026-05-28T22:12:15.716758Z","end":"2026-05-28T22:12:15.85437Z","steps":["trace[1556647511] 'agreement among raft nodes before linearized reading' (duration: 137.440807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:12:15.854451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.293304ms","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":"warn","ts":"2026-05-28T22:12:15.85448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.922649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-28T22:12:15.854589Z","caller":"traceutil/trace.go:171","msg":"trace[1810742680] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13782; }","duration":"112.433997ms","start":"2026-05-28T22:12:15.742095Z","end":"2026-05-28T22:12:15.854528Z","steps":["trace[1810742680] 'agreement among raft nodes before linearized reading' (duration: 112.119821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:12:15.85445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.526053ms","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-28T22:12:15.854602Z","caller":"traceutil/trace.go:171","msg":"trace[2130075610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13782; }","duration":"122.052061ms","start":"2026-05-28T22:12:15.732496Z","end":"2026-05-28T22:12:15.854548Z","steps":["trace[2130075610] 'agreement among raft nodes before linearized reading' (duration: 121.724285ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:12:15.854692Z","caller":"traceutil/trace.go:171","msg":"trace[1768971794] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13782; }","duration":"108.788937ms","start":"2026-05-28T22:12:15.745863Z","end":"2026-05-28T22:12:15.854652Z","steps":["trace[1768971794] 'agreement among raft nodes before linearized reading' (duration: 108.360779ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:12:24.82064Z","caller":"traceutil/trace.go:171","msg":"trace[484571889] transaction","detail":"{read_only:false; response_revision:13949; number_of_response:1; }","duration":"141.520536ms","start":"2026-05-28T22:12:24.679093Z","end":"2026-05-28T22:12:24.820613Z","steps":["trace[484571889] 'process raft request' (duration: 141.184269ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:12:25.026906Z","caller":"traceutil/trace.go:171","msg":"trace[370954461] transaction","detail":"{read_only:false; response_revision:13950; number_of_response:1; }","duration":"109.296837ms","start":"2026-05-28T22:12:24.917577Z","end":"2026-05-28T22:12:25.026874Z","steps":["trace[370954461] 'process raft request' (duration: 56.899621ms)","trace[370954461] 'compare' (duration: 52.218583ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:13:55.214818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.226621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:13:55.215499Z","caller":"traceutil/trace.go:171","msg":"trace[1977806272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14988; }","duration":"199.957105ms","start":"2026-05-28T22:13:55.015514Z","end":"2026-05-28T22:13:55.215471Z","steps":["trace[1977806272] 'range keys from in-memory index tree' (duration: 199.078349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:13:55.215262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.71063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-05-28T22:13:55.215794Z","caller":"traceutil/trace.go:171","msg":"trace[1521368035] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:14988; }","duration":"189.26526ms","start":"2026-05-28T22:13:55.026494Z","end":"2026-05-28T22:13:55.215759Z","steps":["trace[1521368035] 'range keys from in-memory index tree' (duration: 188.535337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:13:55.21539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.29607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-05-28T22:13:55.215887Z","caller":"traceutil/trace.go:171","msg":"trace[710710456] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14988; }","duration":"190.789559ms","start":"2026-05-28T22:13:55.025075Z","end":"2026-05-28T22:13:55.215864Z","steps":["trace[710710456] 'range keys from in-memory index tree' (duration: 190.208618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:13:55.215343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.227788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:13:55.21601Z","caller":"traceutil/trace.go:171","msg":"trace[2090486632] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:14988; }","duration":"191.945322ms","start":"2026-05-28T22:13:55.024038Z","end":"2026-05-28T22:13:55.215984Z","steps":["trace[2090486632] 'range keys from in-memory index tree' (duration: 191.134866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:13:55.215384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.953131ms","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-28T22:13:55.216123Z","caller":"traceutil/trace.go:171","msg":"trace[1498386105] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14988; }","duration":"193.746856ms","start":"2026-05-28T22:13:55.022361Z","end":"2026-05-28T22:13:55.216108Z","steps":["trace[1498386105] 'range keys from in-memory index tree' (duration: 192.877509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:13:55.216186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.515666ms","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-28T22:13:55.216287Z","caller":"traceutil/trace.go:171","msg":"trace[240250813] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14988; }","duration":"189.718919ms","start":"2026-05-28T22:13:55.026546Z","end":"2026-05-28T22:13:55.216265Z","steps":["trace[240250813] 'range keys from in-memory index tree' (duration: 188.829392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:13:56.349061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.339005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-05-28T22:13:56.349145Z","caller":"traceutil/trace.go:171","msg":"trace[342599292] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14992; }","duration":"246.501258ms","start":"2026-05-28T22:13:56.102624Z","end":"2026-05-28T22:13:56.349125Z","steps":["trace[342599292] 'range keys from in-memory index tree' (duration: 246.166952ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:13:56.524468Z","caller":"traceutil/trace.go:171","msg":"trace[1690579129] linearizableReadLoop","detail":"{readStateIndex:15504; appliedIndex:15503; }","duration":"145.217926ms","start":"2026-05-28T22:13:56.37921Z","end":"2026-05-28T22:13:56.524428Z","steps":["trace[1690579129] 'read index received' (duration: 120.499342ms)","trace[1690579129] 'applied index is now lower than readState.Index' (duration: 24.716954ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:13:56.52472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.491391ms","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-28T22:13:56.524827Z","caller":"traceutil/trace.go:171","msg":"trace[837300679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14994; }","duration":"145.624583ms","start":"2026-05-28T22:13:56.379183Z","end":"2026-05-28T22:13:56.524807Z","steps":["trace[837300679] 'agreement among raft nodes before linearized reading' (duration: 145.409799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:13:56.525049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.920451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-28T22:13:56.525067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.065497ms","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-28T22:13:56.52511Z","caller":"traceutil/trace.go:171","msg":"trace[944832377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14994; }","duration":"142.022714ms","start":"2026-05-28T22:13:56.383074Z","end":"2026-05-28T22:13:56.525097Z","steps":["trace[944832377] 'agreement among raft nodes before linearized reading' (duration: 141.881851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:13:56.525057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.026143ms","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-28T22:13:56.525179Z","caller":"traceutil/trace.go:171","msg":"trace[1084300570] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14994; }","duration":"138.177601ms","start":"2026-05-28T22:13:56.386947Z","end":"2026-05-28T22:13:56.525124Z","steps":["trace[1084300570] 'agreement among raft nodes before linearized reading' (duration: 137.902765ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:13:56.525203Z","caller":"traceutil/trace.go:171","msg":"trace[750766455] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14994; }","duration":"143.224808ms","start":"2026-05-28T22:13:56.381963Z","end":"2026-05-28T22:13:56.525188Z","steps":["trace[750766455] 'agreement among raft nodes before linearized reading' (duration: 142.747908ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:13:56.525466Z","caller":"traceutil/trace.go:171","msg":"trace[749896802] transaction","detail":"{read_only:false; response_revision:14994; number_of_response:1; }","duration":"146.51042ms","start":"2026-05-28T22:13:56.378106Z","end":"2026-05-28T22:13:56.524616Z","steps":["trace[749896802] 'process raft request' (duration: 121.615542ms)","trace[749896802] 'compare' (duration: 24.448949ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:15:19.254899Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13030} {"level":"info","ts":"2026-05-28T22:15:19.335802Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13030,"took":"77.827095ms","hash":3448255750,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":31264768,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-28T22:15:19.335859Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3448255750,"revision":13030,"compact-revision":10980} {"level":"info","ts":"2026-05-28T22:19:47.522645Z","caller":"traceutil/trace.go:171","msg":"trace[1897766554] transaction","detail":"{read_only:false; response_revision:17637; number_of_response:1; }","duration":"162.082864ms","start":"2026-05-28T22:19:47.360539Z","end":"2026-05-28T22:19:47.522621Z","steps":["trace[1897766554] 'process raft request' (duration: 161.924371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:19:47.990841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.727565ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-28T22:19:47.991156Z","caller":"traceutil/trace.go:171","msg":"trace[591283608] linearizableReadLoop","detail":"{readStateIndex:18234; appliedIndex:18233; }","duration":"405.278716ms","start":"2026-05-28T22:19:47.585814Z","end":"2026-05-28T22:19:47.991092Z","steps":["trace[591283608] 'read index received' (duration: 67.641514ms)","trace[591283608] 'applied index is now lower than readState.Index' (duration: 337.635382ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:19:47.991516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.674953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:19:47.991572Z","caller":"traceutil/trace.go:171","msg":"trace[851804459] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:17638; }","duration":"405.800416ms","start":"2026-05-28T22:19:47.585756Z","end":"2026-05-28T22:19:47.991556Z","steps":["trace[851804459] 'agreement among raft nodes before linearized reading' (duration: 405.649053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:19:47.991609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:19:47.585732Z","time spent":"405.867638ms","remote":"127.0.0.1:53240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:19:47.991686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.696815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-28T22:19:47.991736Z","caller":"traceutil/trace.go:171","msg":"trace[540381890] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17638; }","duration":"269.809567ms","start":"2026-05-28T22:19:47.721914Z","end":"2026-05-28T22:19:47.991723Z","steps":["trace[540381890] 'agreement among raft nodes before linearized reading' (duration: 269.615313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:19:47.992048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.71467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"warn","ts":"2026-05-28T22:19:47.992162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.981305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-t9jc6\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-05-28T22:19:47.992095Z","caller":"traceutil/trace.go:171","msg":"trace[693175718] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17638; }","duration":"187.775411ms","start":"2026-05-28T22:19:47.804307Z","end":"2026-05-28T22:19:47.992082Z","steps":["trace[693175718] 'agreement among raft nodes before linearized reading' (duration: 187.660089ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:19:47.992208Z","caller":"traceutil/trace.go:171","msg":"trace[747435458] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-t9jc6; range_end:; response_count:1; response_revision:17638; }","duration":"197.068958ms","start":"2026-05-28T22:19:47.795129Z","end":"2026-05-28T22:19:47.992198Z","steps":["trace[747435458] 'agreement among raft nodes before linearized reading' (duration: 196.943944ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:19:47.991222Z","caller":"traceutil/trace.go:171","msg":"trace[2143541752] transaction","detail":"{read_only:false; response_revision:17638; number_of_response:1; }","duration":"336.388819ms","start":"2026-05-28T22:19:47.654807Z","end":"2026-05-28T22:19:47.991196Z","steps":["trace[2143541752] 'process raft request' (duration: 336.196856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:19:47.992419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.041029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-bhm88\" ","response":"range_response_count:1 size:20866"} {"level":"info","ts":"2026-05-28T22:19:47.992447Z","caller":"traceutil/trace.go:171","msg":"trace[755441238] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-bhm88; range_end:; response_count:1; response_revision:17638; }","duration":"253.09299ms","start":"2026-05-28T22:19:47.739347Z","end":"2026-05-28T22:19:47.99244Z","steps":["trace[755441238] 'agreement among raft nodes before linearized reading' (duration: 253.001458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:19:47.992673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:19:47.654779Z","time spent":"337.648393ms","remote":"127.0.0.1:43634","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-28T22:19:48.165437Z","caller":"traceutil/trace.go:171","msg":"trace[297675823] transaction","detail":"{read_only:false; response_revision:17639; number_of_response:1; }","duration":"154.174654ms","start":"2026-05-28T22:19:48.011245Z","end":"2026-05-28T22:19:48.16542Z","steps":["trace[297675823] 'process raft request' (duration: 154.056552ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:20:19.261709Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15523} {"level":"info","ts":"2026-05-28T22:20:19.360027Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15523,"took":"94.309848ms","hash":1003985005,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":32727040,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-28T22:20:19.36008Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1003985005,"revision":15523,"compact-revision":13030} {"level":"info","ts":"2026-05-28T22:23:19.275386Z","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-28T22:23:19.280312Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-28T22:23:19.280527Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-28T22:25:03.26163Z","caller":"traceutil/trace.go:171","msg":"trace[1757626376] transaction","detail":"{read_only:false; response_revision:20053; number_of_response:1; }","duration":"147.135991ms","start":"2026-05-28T22:25:03.114466Z","end":"2026-05-28T22:25:03.261602Z","steps":["trace[1757626376] 'process raft request' (duration: 146.885366ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:25:09.762138Z","caller":"traceutil/trace.go:171","msg":"trace[709942535] transaction","detail":"{read_only:false; response_revision:20093; number_of_response:1; }","duration":"226.887206ms","start":"2026-05-28T22:25:09.535224Z","end":"2026-05-28T22:25:09.762111Z","steps":["trace[709942535] 'process raft request' (duration: 226.719343ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:25:09.890883Z","caller":"traceutil/trace.go:171","msg":"trace[850025797] transaction","detail":"{read_only:false; response_revision:20094; number_of_response:1; }","duration":"198.410158ms","start":"2026-05-28T22:25:09.692435Z","end":"2026-05-28T22:25:09.890845Z","steps":["trace[850025797] 'process raft request' (duration: 98.806326ms)","trace[850025797] 'compare' (duration: 99.139394ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:25:10.1662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.730431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-28T22:25:10.166222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.830561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:25:10.16629Z","caller":"traceutil/trace.go:171","msg":"trace[294262984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20097; }","duration":"111.862553ms","start":"2026-05-28T22:25:10.054406Z","end":"2026-05-28T22:25:10.166268Z","steps":["trace[294262984] 'range keys from in-memory index tree' (duration: 111.452966ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:25:10.166342Z","caller":"traceutil/trace.go:171","msg":"trace[403553465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20097; }","duration":"150.995463ms","start":"2026-05-28T22:25:10.015317Z","end":"2026-05-28T22:25:10.166313Z","steps":["trace[403553465] 'range keys from in-memory index tree' (duration: 150.753159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:25:10.16643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.945015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T22:25:10.166526Z","caller":"traceutil/trace.go:171","msg":"trace[1208384668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20097; }","duration":"112.070657ms","start":"2026-05-28T22:25:10.054433Z","end":"2026-05-28T22:25:10.166503Z","steps":["trace[1208384668] 'range keys from in-memory index tree' (duration: 111.783622ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:25:10.353798Z","caller":"traceutil/trace.go:171","msg":"trace[219540530] transaction","detail":"{read_only:false; response_revision:20098; number_of_response:1; }","duration":"182.767554ms","start":"2026-05-28T22:25:10.170994Z","end":"2026-05-28T22:25:10.353761Z","steps":["trace[219540530] 'process raft request' (duration: 182.523009ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:25:10.357121Z","caller":"traceutil/trace.go:171","msg":"trace[681522430] transaction","detail":"{read_only:false; response_revision:20099; number_of_response:1; }","duration":"186.083555ms","start":"2026-05-28T22:25:10.171017Z","end":"2026-05-28T22:25:10.357101Z","steps":["trace[681522430] 'process raft request' (duration: 185.933573ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:25:10.35734Z","caller":"traceutil/trace.go:171","msg":"trace[169242760] transaction","detail":"{read_only:false; response_revision:20100; number_of_response:1; }","duration":"184.6758ms","start":"2026-05-28T22:25:10.172595Z","end":"2026-05-28T22:25:10.357271Z","steps":["trace[169242760] 'process raft request' (duration: 184.468496ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:25:19.278728Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18077} {"level":"info","ts":"2026-05-28T22:25:19.420606Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18077,"took":"137.829175ms","hash":2619792014,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":29450240,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-28T22:25:19.420672Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2619792014,"revision":18077,"compact-revision":15523} {"level":"info","ts":"2026-05-28T22:30:19.285079Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20152} {"level":"info","ts":"2026-05-28T22:30:19.388714Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20152,"took":"99.556705ms","hash":2911484063,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":41181184,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-05-28T22:30:19.388821Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2911484063,"revision":20152,"compact-revision":18077} {"level":"info","ts":"2026-05-28T22:32:07.566804Z","caller":"traceutil/trace.go:171","msg":"trace[1428584957] transaction","detail":"{read_only:false; response_revision:24354; number_of_response:1; }","duration":"139.107867ms","start":"2026-05-28T22:32:07.427662Z","end":"2026-05-28T22:32:07.56677Z","steps":["trace[1428584957] 'process raft request' (duration: 109.976195ms)","trace[1428584957] 'compare' (duration: 28.805776ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:07.571606Z","caller":"traceutil/trace.go:171","msg":"trace[420832323] linearizableReadLoop","detail":"{readStateIndex:25153; appliedIndex:25151; }","duration":"120.399242ms","start":"2026-05-28T22:32:07.45118Z","end":"2026-05-28T22:32:07.571579Z","steps":["trace[420832323] 'read index received' (duration: 86.54551ms)","trace[420832323] 'applied index is now lower than readState.Index' (duration: 33.852852ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:07.57167Z","caller":"traceutil/trace.go:171","msg":"trace[185311716] transaction","detail":"{read_only:false; response_revision:24355; number_of_response:1; }","duration":"122.390399ms","start":"2026-05-28T22:32:07.449245Z","end":"2026-05-28T22:32:07.571635Z","steps":["trace[185311716] 'process raft request' (duration: 121.981661ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:07.571919Z","caller":"traceutil/trace.go:171","msg":"trace[713463876] transaction","detail":"{read_only:false; response_revision:24356; number_of_response:1; }","duration":"111.38478ms","start":"2026-05-28T22:32:07.460524Z","end":"2026-05-28T22:32:07.571909Z","steps":["trace[713463876] 'process raft request' (duration: 110.899721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:07.571926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.718778ms","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-28T22:32:07.571967Z","caller":"traceutil/trace.go:171","msg":"trace[1437490785] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24356; }","duration":"120.8076ms","start":"2026-05-28T22:32:07.451145Z","end":"2026-05-28T22:32:07.571952Z","steps":["trace[1437490785] 'agreement among raft nodes before linearized reading' (duration: 120.553434ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:14.09913Z","caller":"traceutil/trace.go:171","msg":"trace[484400826] transaction","detail":"{read_only:false; response_revision:24494; number_of_response:1; }","duration":"103.080543ms","start":"2026-05-28T22:32:13.996017Z","end":"2026-05-28T22:32:14.099098Z","steps":["trace[484400826] 'process raft request' (duration: 88.355895ms)","trace[484400826] 'compare' (duration: 13.913253ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:19.317814Z","caller":"traceutil/trace.go:171","msg":"trace[2057160186] transaction","detail":"{read_only:false; response_revision:24537; number_of_response:1; }","duration":"211.285484ms","start":"2026-05-28T22:32:19.106505Z","end":"2026-05-28T22:32:19.31779Z","steps":["trace[2057160186] 'process raft request' (duration: 211.143341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:19.561388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.831715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T22:32:19.561482Z","caller":"traceutil/trace.go:171","msg":"trace[2054577353] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:24537; }","duration":"125.999598ms","start":"2026-05-28T22:32:19.435462Z","end":"2026-05-28T22:32:19.561462Z","steps":["trace[2054577353] 'count revisions from in-memory index tree' (duration: 125.675062ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:19.802675Z","caller":"traceutil/trace.go:171","msg":"trace[1234850028] transaction","detail":"{read_only:false; response_revision:24538; number_of_response:1; }","duration":"182.596761ms","start":"2026-05-28T22:32:19.620041Z","end":"2026-05-28T22:32:19.802638Z","steps":["trace[1234850028] 'process raft request' (duration: 182.340546ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:20.080637Z","caller":"traceutil/trace.go:171","msg":"trace[1747562552] linearizableReadLoop","detail":"{readStateIndex:25343; appliedIndex:25341; }","duration":"432.049487ms","start":"2026-05-28T22:32:19.648564Z","end":"2026-05-28T22:32:20.080613Z","steps":["trace[1747562552] 'read index received' (duration: 153.861696ms)","trace[1747562552] 'applied index is now lower than readState.Index' (duration: 278.186781ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:20.080829Z","caller":"traceutil/trace.go:171","msg":"trace[1605582881] transaction","detail":"{read_only:false; response_revision:24539; number_of_response:1; }","duration":"445.053674ms","start":"2026-05-28T22:32:19.635752Z","end":"2026-05-28T22:32:20.080806Z","steps":["trace[1605582881] 'process raft request' (duration: 403.245411ms)","trace[1605582881] 'compare' (duration: 41.451745ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:20.080955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:19.635739Z","time spent":"445.130546ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:20.081081Z","caller":"traceutil/trace.go:171","msg":"trace[122594655] transaction","detail":"{read_only:false; response_revision:24540; number_of_response:1; }","duration":"396.834171ms","start":"2026-05-28T22:32:19.684222Z","end":"2026-05-28T22:32:20.081057Z","steps":["trace[122594655] 'process raft request' (duration: 396.342741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:20.081212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:19.684206Z","time spent":"396.930473ms","remote":"127.0.0.1:43634","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-28T22:32:20.08166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.096237ms","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-28T22:32:20.081689Z","caller":"traceutil/trace.go:171","msg":"trace[662481642] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24540; }","duration":"433.151338ms","start":"2026-05-28T22:32:19.648528Z","end":"2026-05-28T22:32:20.081679Z","steps":["trace[662481642] 'agreement among raft nodes before linearized reading' (duration: 433.059936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:20.081709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:19.648519Z","time spent":"433.185418ms","remote":"127.0.0.1:43634","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-28T22:32:20.081815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.266361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:20.081886Z","caller":"traceutil/trace.go:171","msg":"trace[831171000] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:24540; }","duration":"251.368603ms","start":"2026-05-28T22:32:19.830496Z","end":"2026-05-28T22:32:20.081865Z","steps":["trace[831171000] 'agreement among raft nodes before linearized reading' (duration: 251.264201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:20.404131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.232504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:20.404295Z","caller":"traceutil/trace.go:171","msg":"trace[626880452] transaction","detail":"{read_only:false; response_revision:24542; number_of_response:1; }","duration":"284.282218ms","start":"2026-05-28T22:32:20.119998Z","end":"2026-05-28T22:32:20.40428Z","steps":["trace[626880452] 'process raft request' (duration: 284.230727ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:20.404588Z","caller":"traceutil/trace.go:171","msg":"trace[1367797763] transaction","detail":"{read_only:false; response_revision:24541; number_of_response:1; }","duration":"319.454953ms","start":"2026-05-28T22:32:20.085125Z","end":"2026-05-28T22:32:20.404579Z","steps":["trace[1367797763] 'process raft request' (duration: 138.694428ms)","trace[1367797763] 'compare' (duration: 179.884158ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:20.404638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:20.085114Z","time spent":"319.496404ms","remote":"127.0.0.1:43634","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-28T22:32:20.404711Z","caller":"traceutil/trace.go:171","msg":"trace[1747150891] linearizableReadLoop","detail":"{readStateIndex:25345; appliedIndex:25344; }","duration":"318.020536ms","start":"2026-05-28T22:32:20.086682Z","end":"2026-05-28T22:32:20.404702Z","steps":["trace[1747150891] 'read index received' (duration: 137.22573ms)","trace[1747150891] 'applied index is now lower than readState.Index' (duration: 180.794026ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:20.404801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.122058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:20.404828Z","caller":"traceutil/trace.go:171","msg":"trace[2077783471] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:24542; }","duration":"318.18221ms","start":"2026-05-28T22:32:20.086637Z","end":"2026-05-28T22:32:20.404819Z","steps":["trace[2077783471] 'agreement among raft nodes before linearized reading' (duration: 318.135799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:20.404853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:20.086578Z","time spent":"318.265791ms","remote":"127.0.0.1:53286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true "} {"level":"info","ts":"2026-05-28T22:32:20.812994Z","caller":"traceutil/trace.go:171","msg":"trace[1715223546] linearizableReadLoop","detail":"{readStateIndex:25347; appliedIndex:25346; }","duration":"408.218715ms","start":"2026-05-28T22:32:20.404752Z","end":"2026-05-28T22:32:20.812971Z","steps":["trace[1715223546] 'read index received' (duration: 342.435579ms)","trace[1715223546] 'applied index is now lower than readState.Index' (duration: 65.782286ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:20.813095Z","caller":"traceutil/trace.go:171","msg":"trace[390992267] transaction","detail":"{read_only:false; response_revision:24543; number_of_response:1; }","duration":"463.416082ms","start":"2026-05-28T22:32:20.349623Z","end":"2026-05-28T22:32:20.813039Z","steps":["trace[390992267] 'process raft request' (duration: 397.652006ms)","trace[390992267] 'compare' (duration: 65.583051ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:20.813206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.620012ms","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-28T22:32:20.813235Z","caller":"traceutil/trace.go:171","msg":"trace[57368146] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24543; }","duration":"561.696085ms","start":"2026-05-28T22:32:20.25153Z","end":"2026-05-28T22:32:20.813226Z","steps":["trace[57368146] 'agreement among raft nodes before linearized reading' (duration: 561.555852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:20.813259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:20.251511Z","time spent":"561.742245ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T22:32:20.813291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:20.349598Z","time spent":"463.579755ms","remote":"127.0.0.1:43634","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-28T22:32:20.813444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.166908ms","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-28T22:32:20.813468Z","caller":"traceutil/trace.go:171","msg":"trace[283083079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24543; }","duration":"633.190118ms","start":"2026-05-28T22:32:20.180271Z","end":"2026-05-28T22:32:20.813461Z","steps":["trace[283083079] 'agreement among raft nodes before linearized reading' (duration: 633.114307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:20.813484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:20.180218Z","time spent":"633.26237ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T22:32:20.813558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.264005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:20.813579Z","caller":"traceutil/trace.go:171","msg":"trace[700260471] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:24543; }","duration":"191.289635ms","start":"2026-05-28T22:32:20.622283Z","end":"2026-05-28T22:32:20.813572Z","steps":["trace[700260471] 'agreement among raft nodes before linearized reading' (duration: 191.251825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:21.235659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.308766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:21.236039Z","caller":"traceutil/trace.go:171","msg":"trace[1164577875] transaction","detail":"{read_only:false; response_revision:24551; number_of_response:1; }","duration":"151.817517ms","start":"2026-05-28T22:32:21.084195Z","end":"2026-05-28T22:32:21.236013Z","steps":["trace[1164577875] 'process raft request' (duration: 151.779286ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:21.236209Z","caller":"traceutil/trace.go:171","msg":"trace[1426237323] transaction","detail":"{read_only:false; response_revision:24548; number_of_response:1; }","duration":"415.787709ms","start":"2026-05-28T22:32:20.820411Z","end":"2026-05-28T22:32:21.236199Z","steps":["trace[1426237323] 'process raft request' (duration: 415.435263ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:21.236183Z","caller":"traceutil/trace.go:171","msg":"trace[707836083] transaction","detail":"{read_only:false; response_revision:24547; number_of_response:1; }","duration":"416.171838ms","start":"2026-05-28T22:32:20.819976Z","end":"2026-05-28T22:32:21.236148Z","steps":["trace[707836083] 'process raft request' (duration: 415.82137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:21.236258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:20.820403Z","time spent":"415.82791ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:21.236327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:20.819959Z","time spent":"416.287999ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:21.236368Z","caller":"traceutil/trace.go:171","msg":"trace[409364898] transaction","detail":"{read_only:false; response_revision:24549; number_of_response:1; }","duration":"292.498503ms","start":"2026-05-28T22:32:20.943861Z","end":"2026-05-28T22:32:21.236359Z","steps":["trace[409364898] 'process raft request' (duration: 292.021525ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:21.236444Z","caller":"traceutil/trace.go:171","msg":"trace[382975472] transaction","detail":"{read_only:false; response_revision:24550; number_of_response:1; }","duration":"256.91223ms","start":"2026-05-28T22:32:20.979521Z","end":"2026-05-28T22:32:21.236433Z","steps":["trace[382975472] 'process raft request' (duration: 256.39397ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:21.236508Z","caller":"traceutil/trace.go:171","msg":"trace[1958393582] linearizableReadLoop","detail":"{readStateIndex:25354; appliedIndex:25348; }","duration":"220.52395ms","start":"2026-05-28T22:32:21.015976Z","end":"2026-05-28T22:32:21.2365Z","steps":["trace[1958393582] 'read index received' (duration: 10.298096ms)","trace[1958393582] 'applied index is now lower than readState.Index' (duration: 210.223974ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:21.236578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.602911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:21.23659Z","caller":"traceutil/trace.go:171","msg":"trace[1833800992] transaction","detail":"{read_only:false; response_revision:24545; number_of_response:1; }","duration":"416.819759ms","start":"2026-05-28T22:32:20.819756Z","end":"2026-05-28T22:32:21.236576Z","steps":["trace[1833800992] 'process raft request' (duration: 206.507204ms)","trace[1833800992] 'compare' (duration: 209.088162ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:21.236654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:20.819724Z","time spent":"416.895671ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:21.236889Z","caller":"traceutil/trace.go:171","msg":"trace[935159470] transaction","detail":"{read_only:false; response_revision:24546; number_of_response:1; }","duration":"417.052894ms","start":"2026-05-28T22:32:20.819818Z","end":"2026-05-28T22:32:21.236871Z","steps":["trace[935159470] 'process raft request' (duration: 415.927832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:21.236941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:20.8198Z","time spent":"417.114035ms","remote":"127.0.0.1:43634","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-28T22:32:21.236599Z","caller":"traceutil/trace.go:171","msg":"trace[1445897176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24551; }","duration":"220.660562ms","start":"2026-05-28T22:32:21.015931Z","end":"2026-05-28T22:32:21.236592Z","steps":["trace[1445897176] 'agreement among raft nodes before linearized reading' (duration: 220.599251ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:27.087813Z","caller":"traceutil/trace.go:171","msg":"trace[75666621] transaction","detail":"{read_only:false; response_revision:24588; number_of_response:1; }","duration":"141.329378ms","start":"2026-05-28T22:32:26.946459Z","end":"2026-05-28T22:32:27.087789Z","steps":["trace[75666621] 'process raft request' (duration: 134.435097ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:27.476585Z","caller":"traceutil/trace.go:171","msg":"trace[557330198] linearizableReadLoop","detail":"{readStateIndex:25396; appliedIndex:25395; }","duration":"124.588661ms","start":"2026-05-28T22:32:27.351958Z","end":"2026-05-28T22:32:27.476547Z","steps":["trace[557330198] 'read index received' (duration: 122.94261ms)","trace[557330198] 'applied index is now lower than readState.Index' (duration: 1.643991ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:27.4768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.824875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-28T22:32:27.476933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.063142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-05-28T22:32:27.476936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.653415ms","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-28T22:32:27.477001Z","caller":"traceutil/trace.go:171","msg":"trace[1762505951] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24591; }","duration":"123.162704ms","start":"2026-05-28T22:32:27.35382Z","end":"2026-05-28T22:32:27.476983Z","steps":["trace[1762505951] 'agreement among raft nodes before linearized reading' (duration: 122.902079ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:27.477011Z","caller":"traceutil/trace.go:171","msg":"trace[938704713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24591; }","duration":"121.776218ms","start":"2026-05-28T22:32:27.355217Z","end":"2026-05-28T22:32:27.476993Z","steps":["trace[938704713] 'agreement among raft nodes before linearized reading' (duration: 121.567044ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:27.476945Z","caller":"traceutil/trace.go:171","msg":"trace[307332484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24591; }","duration":"124.958838ms","start":"2026-05-28T22:32:27.351925Z","end":"2026-05-28T22:32:27.476884Z","steps":["trace[307332484] 'agreement among raft nodes before linearized reading' (duration: 124.704453ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:27.476612Z","caller":"traceutil/trace.go:171","msg":"trace[663069212] transaction","detail":"{read_only:false; response_revision:24591; number_of_response:1; }","duration":"128.062967ms","start":"2026-05-28T22:32:27.348503Z","end":"2026-05-28T22:32:27.476566Z","steps":["trace[663069212] 'process raft request' (duration: 126.464367ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:27.69965Z","caller":"traceutil/trace.go:171","msg":"trace[419121428] transaction","detail":"{read_only:false; response_revision:24592; number_of_response:1; }","duration":"218.412749ms","start":"2026-05-28T22:32:27.481211Z","end":"2026-05-28T22:32:27.699624Z","steps":["trace[419121428] 'process raft request' (duration: 189.47084ms)","trace[419121428] 'compare' (duration: 28.802876ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:27.762273Z","caller":"traceutil/trace.go:171","msg":"trace[1810120651] transaction","detail":"{read_only:false; response_revision:24596; number_of_response:1; }","duration":"173.820624ms","start":"2026-05-28T22:32:27.588432Z","end":"2026-05-28T22:32:27.762253Z","steps":["trace[1810120651] 'process raft request' (duration: 173.777073ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:27.762589Z","caller":"traceutil/trace.go:171","msg":"trace[1597423869] transaction","detail":"{read_only:false; response_revision:24593; number_of_response:1; }","duration":"280.650728ms","start":"2026-05-28T22:32:27.481931Z","end":"2026-05-28T22:32:27.762581Z","steps":["trace[1597423869] 'process raft request' (duration: 280.094447ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:27.762681Z","caller":"traceutil/trace.go:171","msg":"trace[549576869] transaction","detail":"{read_only:false; response_revision:24594; number_of_response:1; }","duration":"279.741691ms","start":"2026-05-28T22:32:27.482933Z","end":"2026-05-28T22:32:27.762675Z","steps":["trace[549576869] 'process raft request' (duration: 279.222472ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:27.762766Z","caller":"traceutil/trace.go:171","msg":"trace[850541752] transaction","detail":"{read_only:false; response_revision:24595; number_of_response:1; }","duration":"261.307541ms","start":"2026-05-28T22:32:27.501451Z","end":"2026-05-28T22:32:27.762758Z","steps":["trace[850541752] 'process raft request' (duration: 260.73355ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:28.065629Z","caller":"traceutil/trace.go:171","msg":"trace[689013139] linearizableReadLoop","detail":"{readStateIndex:25402; appliedIndex:25401; }","duration":"198.188825ms","start":"2026-05-28T22:32:27.867415Z","end":"2026-05-28T22:32:28.065603Z","steps":["trace[689013139] 'read index received' (duration: 198.014352ms)","trace[689013139] 'applied index is now lower than readState.Index' (duration: 173.363µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:28.065669Z","caller":"traceutil/trace.go:171","msg":"trace[1302403241] transaction","detail":"{read_only:false; response_revision:24597; number_of_response:1; }","duration":"229.929287ms","start":"2026-05-28T22:32:27.835709Z","end":"2026-05-28T22:32:28.065638Z","steps":["trace[1302403241] 'process raft request' (duration: 229.671603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:28.066117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.581104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T22:32:28.066179Z","caller":"traceutil/trace.go:171","msg":"trace[1200303277] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:24597; }","duration":"198.803077ms","start":"2026-05-28T22:32:27.867363Z","end":"2026-05-28T22:32:28.066166Z","steps":["trace[1200303277] 'agreement among raft nodes before linearized reading' (duration: 198.361499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:28.352689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.314396ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-28T22:32:28.353141Z","caller":"traceutil/trace.go:171","msg":"trace[453209963] transaction","detail":"{read_only:false; response_revision:24598; number_of_response:1; }","duration":"227.428661ms","start":"2026-05-28T22:32:28.125684Z","end":"2026-05-28T22:32:28.353112Z","steps":["trace[453209963] 'process raft request' (duration: 227.201276ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:28.353196Z","caller":"traceutil/trace.go:171","msg":"trace[1739321388] linearizableReadLoop","detail":"{readStateIndex:25403; appliedIndex:25402; }","duration":"287.460847ms","start":"2026-05-28T22:32:28.065697Z","end":"2026-05-28T22:32:28.353158Z","steps":["trace[1739321388] 'read index received' (duration: 48.537269ms)","trace[1739321388] 'applied index is now lower than readState.Index' (duration: 238.920718ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:28.353395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.136199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:28.353434Z","caller":"traceutil/trace.go:171","msg":"trace[1089614295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24598; }","duration":"337.23516ms","start":"2026-05-28T22:32:28.016189Z","end":"2026-05-28T22:32:28.353424Z","steps":["trace[1089614295] 'agreement among raft nodes before linearized reading' (duration: 337.135728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:28.353467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:28.016166Z","time spent":"337.293832ms","remote":"127.0.0.1:43360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T22:32:28.564453Z","caller":"traceutil/trace.go:171","msg":"trace[1083558205] linearizableReadLoop","detail":"{readStateIndex:25405; appliedIndex:25404; }","duration":"105.059541ms","start":"2026-05-28T22:32:28.459365Z","end":"2026-05-28T22:32:28.564424Z","steps":["trace[1083558205] 'read index received' (duration: 103.828918ms)","trace[1083558205] 'applied index is now lower than readState.Index' (duration: 1.229663ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:28.564718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.280255ms","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-28T22:32:28.564781Z","caller":"traceutil/trace.go:171","msg":"trace[854414319] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24599; }","duration":"105.439988ms","start":"2026-05-28T22:32:28.459327Z","end":"2026-05-28T22:32:28.564767Z","steps":["trace[854414319] 'agreement among raft nodes before linearized reading' (duration: 105.193424ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:28.565059Z","caller":"traceutil/trace.go:171","msg":"trace[1969054433] transaction","detail":"{read_only:false; response_revision:24599; number_of_response:1; }","duration":"119.639638ms","start":"2026-05-28T22:32:28.445408Z","end":"2026-05-28T22:32:28.565048Z","steps":["trace[1969054433] 'process raft request' (duration: 117.860634ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:28.750971Z","caller":"traceutil/trace.go:171","msg":"trace[592919090] transaction","detail":"{read_only:false; response_revision:24600; number_of_response:1; }","duration":"180.533932ms","start":"2026-05-28T22:32:28.570407Z","end":"2026-05-28T22:32:28.750941Z","steps":["trace[592919090] 'process raft request' (duration: 175.093608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:29.056322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.793349ms","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-28T22:32:29.056411Z","caller":"traceutil/trace.go:171","msg":"trace[2012105212] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24600; }","duration":"177.938082ms","start":"2026-05-28T22:32:28.878451Z","end":"2026-05-28T22:32:29.056389Z","steps":["trace[2012105212] 'range keys from in-memory index tree' (duration: 177.692207ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:29.056523Z","caller":"traceutil/trace.go:171","msg":"trace[856431695] transaction","detail":"{read_only:false; response_revision:24601; number_of_response:1; }","duration":"139.447812ms","start":"2026-05-28T22:32:28.917037Z","end":"2026-05-28T22:32:29.056485Z","steps":["trace[856431695] 'process raft request' (duration: 67.340766ms)","trace[856431695] 'compare' (duration: 71.567606ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:29.199763Z","caller":"traceutil/trace.go:171","msg":"trace[534692298] linearizableReadLoop","detail":"{readStateIndex:25408; appliedIndex:25407; }","duration":"164.641419ms","start":"2026-05-28T22:32:29.035091Z","end":"2026-05-28T22:32:29.199733Z","steps":["trace[534692298] 'read index received' (duration: 145.705821ms)","trace[534692298] 'applied index is now lower than readState.Index' (duration: 18.933388ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:29.19978Z","caller":"traceutil/trace.go:171","msg":"trace[421246452] transaction","detail":"{read_only:false; response_revision:24603; number_of_response:1; }","duration":"111.093045ms","start":"2026-05-28T22:32:29.088618Z","end":"2026-05-28T22:32:29.199711Z","steps":["trace[421246452] 'process raft request' (duration: 110.987783ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:29.199782Z","caller":"traceutil/trace.go:171","msg":"trace[1567850955] transaction","detail":"{read_only:false; response_revision:24602; number_of_response:1; }","duration":"221.758542ms","start":"2026-05-28T22:32:28.977969Z","end":"2026-05-28T22:32:29.199728Z","steps":["trace[1567850955] 'process raft request' (duration: 202.875825ms)","trace[1567850955] 'compare' (duration: 18.48106ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:29.199969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.870814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:29.20001Z","caller":"traceutil/trace.go:171","msg":"trace[411992743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24603; }","duration":"164.975226ms","start":"2026-05-28T22:32:29.035024Z","end":"2026-05-28T22:32:29.199999Z","steps":["trace[411992743] 'agreement among raft nodes before linearized reading' (duration: 164.852004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:29.20025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.013024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:29.200292Z","caller":"traceutil/trace.go:171","msg":"trace[1877500565] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24603; }","duration":"139.131887ms","start":"2026-05-28T22:32:29.061149Z","end":"2026-05-28T22:32:29.200281Z","steps":["trace[1877500565] 'agreement among raft nodes before linearized reading' (duration: 139.019075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:29.200501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.436014ms","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-28T22:32:29.200538Z","caller":"traceutil/trace.go:171","msg":"trace[1789552328] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24603; }","duration":"108.516086ms","start":"2026-05-28T22:32:29.092011Z","end":"2026-05-28T22:32:29.200527Z","steps":["trace[1789552328] 'agreement among raft nodes before linearized reading' (duration: 108.367793ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:29.689359Z","caller":"traceutil/trace.go:171","msg":"trace[725315549] transaction","detail":"{read_only:false; response_revision:24607; number_of_response:1; }","duration":"104.351147ms","start":"2026-05-28T22:32:29.584977Z","end":"2026-05-28T22:32:29.689328Z","steps":["trace[725315549] 'process raft request' (duration: 104.160603ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:29.933273Z","caller":"traceutil/trace.go:171","msg":"trace[802927173] transaction","detail":"{read_only:false; response_revision:24609; number_of_response:1; }","duration":"164.436556ms","start":"2026-05-28T22:32:29.7688Z","end":"2026-05-28T22:32:29.933237Z","steps":["trace[802927173] 'process raft request' (duration: 149.413252ms)","trace[802927173] 'compare' (duration: 14.856471ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:29.933331Z","caller":"traceutil/trace.go:171","msg":"trace[225491506] transaction","detail":"{read_only:false; response_revision:24610; number_of_response:1; }","duration":"158.894071ms","start":"2026-05-28T22:32:29.774408Z","end":"2026-05-28T22:32:29.933302Z","steps":["trace[225491506] 'process raft request' (duration: 158.81712ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:29.933465Z","caller":"traceutil/trace.go:171","msg":"trace[625094513] linearizableReadLoop","detail":"{readStateIndex:25415; appliedIndex:25414; }","duration":"161.705393ms","start":"2026-05-28T22:32:29.771729Z","end":"2026-05-28T22:32:29.933434Z","steps":["trace[625094513] 'read index received' (duration: 146.571797ms)","trace[625094513] 'applied index is now lower than readState.Index' (duration: 15.130676ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:29.933656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.909089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-28T22:32:29.933709Z","caller":"traceutil/trace.go:171","msg":"trace[1645469281] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24610; }","duration":"161.998ms","start":"2026-05-28T22:32:29.771694Z","end":"2026-05-28T22:32:29.933692Z","steps":["trace[1645469281] 'agreement among raft nodes before linearized reading' (duration: 161.829527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:29.933788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.814494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:29.933905Z","caller":"traceutil/trace.go:171","msg":"trace[203245519] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24610; }","duration":"136.057768ms","start":"2026-05-28T22:32:29.797824Z","end":"2026-05-28T22:32:29.933881Z","steps":["trace[203245519] 'agreement among raft nodes before linearized reading' (duration: 135.806214ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:30.190861Z","caller":"traceutil/trace.go:171","msg":"trace[918723771] transaction","detail":"{read_only:false; response_revision:24612; number_of_response:1; }","duration":"120.110735ms","start":"2026-05-28T22:32:30.070724Z","end":"2026-05-28T22:32:30.190835Z","steps":["trace[918723771] 'process raft request' (duration: 107.454625ms)","trace[918723771] 'compare' (duration: 12.11008ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:30.639642Z","caller":"traceutil/trace.go:171","msg":"trace[1020233982] transaction","detail":"{read_only:false; response_revision:24613; number_of_response:1; }","duration":"272.893061ms","start":"2026-05-28T22:32:30.366725Z","end":"2026-05-28T22:32:30.639618Z","steps":["trace[1020233982] 'process raft request' (duration: 272.722558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:31.345358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.688438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:31.345451Z","caller":"traceutil/trace.go:171","msg":"trace[1145224311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24615; }","duration":"329.839531ms","start":"2026-05-28T22:32:31.015591Z","end":"2026-05-28T22:32:31.34543Z","steps":["trace[1145224311] 'range keys from in-memory index tree' (duration: 329.583167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:31.34551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.015558Z","time spent":"329.935592ms","remote":"127.0.0.1:43360","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-28T22:32:31.345858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.283362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:31.345999Z","caller":"traceutil/trace.go:171","msg":"trace[261368801] linearizableReadLoop","detail":"{readStateIndex:25422; appliedIndex:25421; }","duration":"177.497782ms","start":"2026-05-28T22:32:31.16845Z","end":"2026-05-28T22:32:31.345948Z","steps":["trace[261368801] 'read index received' (duration: 61.621µs)","trace[261368801] 'applied index is now lower than readState.Index' (duration: 177.434921ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:31.346067Z","caller":"traceutil/trace.go:171","msg":"trace[523890065] transaction","detail":"{read_only:false; response_revision:24616; number_of_response:1; }","duration":"276.805765ms","start":"2026-05-28T22:32:31.069157Z","end":"2026-05-28T22:32:31.345963Z","steps":["trace[523890065] 'process raft request' (duration: 35.331329ms)","trace[523890065] 'compare' (duration: 240.786573ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:31.346124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.651066ms","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":"info","ts":"2026-05-28T22:32:31.346207Z","caller":"traceutil/trace.go:171","msg":"trace[2073644253] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:24616; }","duration":"177.784738ms","start":"2026-05-28T22:32:31.168402Z","end":"2026-05-28T22:32:31.346187Z","steps":["trace[2073644253] 'agreement among raft nodes before linearized reading' (duration: 177.610775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.133457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.895949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:32.133675Z","caller":"traceutil/trace.go:171","msg":"trace[140581811] linearizableReadLoop","detail":"{readStateIndex:25426; appliedIndex:25422; }","duration":"439.949457ms","start":"2026-05-28T22:32:31.693713Z","end":"2026-05-28T22:32:32.133663Z","steps":["trace[140581811] 'read index received' (duration: 307.900005ms)","trace[140581811] 'applied index is now lower than readState.Index' (duration: 132.048672ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:32.133678Z","caller":"traceutil/trace.go:171","msg":"trace[1691404875] transaction","detail":"{read_only:false; response_revision:24618; number_of_response:1; }","duration":"760.790577ms","start":"2026-05-28T22:32:31.372854Z","end":"2026-05-28T22:32:32.133644Z","steps":["trace[1691404875] 'process raft request' (duration: 760.682095ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:32.133737Z","caller":"traceutil/trace.go:171","msg":"trace[457601105] transaction","detail":"{read_only:false; response_revision:24619; number_of_response:1; }","duration":"760.719236ms","start":"2026-05-28T22:32:31.373011Z","end":"2026-05-28T22:32:32.13373Z","steps":["trace[457601105] 'process raft request' (duration: 760.565063ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:32.133723Z","caller":"traceutil/trace.go:171","msg":"trace[1432743715] transaction","detail":"{read_only:false; response_revision:24617; number_of_response:1; }","duration":"782.193493ms","start":"2026-05-28T22:32:31.351507Z","end":"2026-05-28T22:32:32.1337Z","steps":["trace[1432743715] 'process raft request' (duration: 220.985698ms)","trace[1432743715] 'compare' (duration: 560.43146ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:32.133781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.373Z","time spent":"760.758616ms","remote":"127.0.0.1:43634","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-28T22:32:32.133808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.351459Z","time spent":"782.305835ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:32.133816Z","caller":"traceutil/trace.go:171","msg":"trace[1203752203] transaction","detail":"{read_only:false; response_revision:24620; number_of_response:1; }","duration":"696.174553ms","start":"2026-05-28T22:32:31.437619Z","end":"2026-05-28T22:32:32.133794Z","steps":["trace[1203752203] 'process raft request' (duration: 695.981979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.133831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.372827Z","time spent":"760.917469ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:32.133939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.437595Z","time spent":"696.275894ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:32.134629Z","caller":"traceutil/trace.go:171","msg":"trace[262886479] transaction","detail":"{read_only:false; response_revision:24621; number_of_response:1; }","duration":"194.835843ms","start":"2026-05-28T22:32:31.939775Z","end":"2026-05-28T22:32:32.134611Z","steps":["trace[262886479] 'process raft request' (duration: 193.860244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.134761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.050498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T22:32:32.134804Z","caller":"traceutil/trace.go:171","msg":"trace[1094627785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24621; }","duration":"441.13029ms","start":"2026-05-28T22:32:31.693664Z","end":"2026-05-28T22:32:32.134794Z","steps":["trace[1094627785] 'agreement among raft nodes before linearized reading' (duration: 440.08397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.134826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.693644Z","time spent":"441.177561ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T22:32:32.135053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.32933ms","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-28T22:32:32.135082Z","caller":"traceutil/trace.go:171","msg":"trace[776614178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24621; }","duration":"197.393421ms","start":"2026-05-28T22:32:31.937683Z","end":"2026-05-28T22:32:32.135076Z","steps":["trace[776614178] 'agreement among raft nodes before linearized reading' (duration: 197.31742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.135172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.649879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-28T22:32:32.135198Z","caller":"traceutil/trace.go:171","msg":"trace[865929310] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24621; }","duration":"118.70523ms","start":"2026-05-28T22:32:32.016483Z","end":"2026-05-28T22:32:32.135188Z","steps":["trace[865929310] 'agreement among raft nodes before linearized reading' (duration: 118.648319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.135321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.066996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T22:32:32.135345Z","caller":"traceutil/trace.go:171","msg":"trace[1518350374] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:24621; }","duration":"301.125576ms","start":"2026-05-28T22:32:31.834212Z","end":"2026-05-28T22:32:32.135338Z","steps":["trace[1518350374] 'agreement among raft nodes before linearized reading' (duration: 301.054005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.135438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.834189Z","time spent":"301.169757ms","remote":"127.0.0.1:40918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":54,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:32:32.13667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.273741ms","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-28T22:32:32.136808Z","caller":"traceutil/trace.go:171","msg":"trace[1960807533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24621; }","duration":"424.422553ms","start":"2026-05-28T22:32:31.712357Z","end":"2026-05-28T22:32:32.13678Z","steps":["trace[1960807533] 'agreement among raft nodes before linearized reading' (duration: 422.179091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.136855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.7123Z","time spent":"424.545605ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-28T22:32:32.658496Z","caller":"traceutil/trace.go:171","msg":"trace[1026659028] transaction","detail":"{read_only:false; response_revision:24622; number_of_response:1; }","duration":"518.454046ms","start":"2026-05-28T22:32:32.140003Z","end":"2026-05-28T22:32:32.658457Z","steps":["trace[1026659028] 'process raft request' (duration: 496.772614ms)","trace[1026659028] 'compare' (duration: 21.354616ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:32.659737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.139981Z","time spent":"518.613548ms","remote":"127.0.0.1:43634","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-28T22:32:33.152477Z","caller":"traceutil/trace.go:171","msg":"trace[1055842000] transaction","detail":"{read_only:false; response_revision:24623; number_of_response:1; }","duration":"1.012271783s","start":"2026-05-28T22:32:32.140181Z","end":"2026-05-28T22:32:33.152452Z","steps":["trace[1055842000] 'process raft request' (duration: 1.009236255s)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:33.152853Z","caller":"traceutil/trace.go:171","msg":"trace[2134132182] transaction","detail":"{read_only:false; response_revision:24624; number_of_response:1; }","duration":"1.011833625s","start":"2026-05-28T22:32:32.140992Z","end":"2026-05-28T22:32:33.152825Z","steps":["trace[2134132182] 'process raft request' (duration: 1.011380896s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.153027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.140974Z","time spent":"1.011952457s","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:33.153358Z","caller":"traceutil/trace.go:171","msg":"trace[42340848] transaction","detail":"{read_only:false; response_revision:24625; number_of_response:1; }","duration":"1.01164527s","start":"2026-05-28T22:32:32.141688Z","end":"2026-05-28T22:32:33.153334Z","steps":["trace[42340848] 'process raft request' (duration: 1.010971098s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.153486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.141676Z","time spent":"1.011743232s","remote":"127.0.0.1:43634","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-28T22:32:33.153529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.140155Z","time spent":"1.012399415s","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:33.153734Z","caller":"traceutil/trace.go:171","msg":"trace[1085322455] linearizableReadLoop","detail":"{readStateIndex:25432; appliedIndex:25427; }","duration":"723.475109ms","start":"2026-05-28T22:32:32.430235Z","end":"2026-05-28T22:32:33.15371Z","steps":["trace[1085322455] 'read index received' (duration: 206.535633ms)","trace[1085322455] 'applied index is now lower than readState.Index' (duration: 516.938506ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:33.153828Z","caller":"traceutil/trace.go:171","msg":"trace[1098599974] transaction","detail":"{read_only:false; response_revision:24626; number_of_response:1; }","duration":"958.284991ms","start":"2026-05-28T22:32:32.195534Z","end":"2026-05-28T22:32:33.153819Z","steps":["trace[1098599974] 'process raft request' (duration: 957.664579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.15388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.195514Z","time spent":"958.338591ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:33.154034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.801086ms","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-28T22:32:33.15415Z","caller":"traceutil/trace.go:171","msg":"trace[588453530] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:24626; }","duration":"723.9564ms","start":"2026-05-28T22:32:32.430175Z","end":"2026-05-28T22:32:33.154132Z","steps":["trace[588453530] 'agreement among raft nodes before linearized reading' (duration: 723.722595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.154204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.43015Z","time spent":"724.039701ms","remote":"127.0.0.1:43394","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-28T22:32:33.189281Z","caller":"traceutil/trace.go:171","msg":"trace[1451548634] transaction","detail":"{read_only:false; response_revision:24627; number_of_response:1; }","duration":"542.98062ms","start":"2026-05-28T22:32:32.646275Z","end":"2026-05-28T22:32:33.189256Z","steps":["trace[1451548634] 'process raft request' (duration: 542.732776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.189385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.646209Z","time spent":"543.125493ms","remote":"127.0.0.1:43634","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-28T22:32:33.189478Z","caller":"traceutil/trace.go:171","msg":"trace[930094103] transaction","detail":"{read_only:false; response_revision:24628; number_of_response:1; }","duration":"523.700375ms","start":"2026-05-28T22:32:32.665741Z","end":"2026-05-28T22:32:33.189442Z","steps":["trace[930094103] 'process raft request' (duration: 523.45776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.18955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.606287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T22:32:33.189624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.665709Z","time spent":"523.841117ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:33.189639Z","caller":"traceutil/trace.go:171","msg":"trace[380935423] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:24628; }","duration":"662.712919ms","start":"2026-05-28T22:32:32.526906Z","end":"2026-05-28T22:32:33.189619Z","steps":["trace[380935423] 'agreement among raft nodes before linearized reading' (duration: 662.527895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.189685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.526842Z","time spent":"662.832531ms","remote":"127.0.0.1:39680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:32:33.1897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.332015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:33.189739Z","caller":"traceutil/trace.go:171","msg":"trace[1602085869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24628; }","duration":"173.413107ms","start":"2026-05-28T22:32:33.016315Z","end":"2026-05-28T22:32:33.189728Z","steps":["trace[1602085869] 'agreement among raft nodes before linearized reading' (duration: 173.344406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.190061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.864158ms","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-28T22:32:33.190106Z","caller":"traceutil/trace.go:171","msg":"trace[714557803] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24628; }","duration":"323.951089ms","start":"2026-05-28T22:32:32.866142Z","end":"2026-05-28T22:32:33.190093Z","steps":["trace[714557803] 'agreement among raft nodes before linearized reading' (duration: 323.808637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.190137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.866109Z","time spent":"324.02112ms","remote":"127.0.0.1:43634","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-28T22:32:33.644465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.632801ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-05-28T22:32:33.6446Z","caller":"traceutil/trace.go:171","msg":"trace[1682519676] linearizableReadLoop","detail":"{readStateIndex:25435; appliedIndex:25434; }","duration":"452.881231ms","start":"2026-05-28T22:32:33.191701Z","end":"2026-05-28T22:32:33.644582Z","steps":["trace[1682519676] 'read index received' (duration: 251.148569ms)","trace[1682519676] 'applied index is now lower than readState.Index' (duration: 201.731102ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:33.644685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.975213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:33.644774Z","caller":"traceutil/trace.go:171","msg":"trace[474052870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24628; }","duration":"453.070705ms","start":"2026-05-28T22:32:33.191645Z","end":"2026-05-28T22:32:33.644716Z","steps":["trace[474052870] 'agreement among raft nodes before linearized reading' (duration: 452.983533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.644825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:33.191622Z","time spent":"453.187957ms","remote":"127.0.0.1:43368","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-28T22:32:33.645006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:33.155289Z","time spent":"489.716149ms","remote":"127.0.0.1:43394","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-28T22:32:33.675865Z","caller":"traceutil/trace.go:171","msg":"trace[1298771679] transaction","detail":"{read_only:false; response_revision:24630; number_of_response:1; }","duration":"323.54048ms","start":"2026-05-28T22:32:33.352281Z","end":"2026-05-28T22:32:33.675821Z","steps":["trace[1298771679] 'process raft request' (duration: 323.480619ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:33.675878Z","caller":"traceutil/trace.go:171","msg":"trace[65346951] transaction","detail":"{read_only:false; response_revision:24629; number_of_response:1; }","duration":"481.105226ms","start":"2026-05-28T22:32:33.194739Z","end":"2026-05-28T22:32:33.675844Z","steps":["trace[65346951] 'process raft request' (duration: 480.793711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.676209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:33.194723Z","time spent":"481.418643ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:33.677517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:33.352243Z","time spent":"323.721914ms","remote":"127.0.0.1:43634","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-28T22:32:33.993189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.889982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:33.993296Z","caller":"traceutil/trace.go:171","msg":"trace[2096734022] linearizableReadLoop","detail":"{readStateIndex:25438; appliedIndex:25437; }","duration":"168.679916ms","start":"2026-05-28T22:32:33.824599Z","end":"2026-05-28T22:32:33.993278Z","steps":["trace[2096734022] 'read index received' (duration: 38.811µs)","trace[2096734022] 'applied index is now lower than readState.Index' (duration: 168.638995ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:33.993423Z","caller":"traceutil/trace.go:171","msg":"trace[1913896277] transaction","detail":"{read_only:false; response_revision:24631; number_of_response:1; }","duration":"346.975415ms","start":"2026-05-28T22:32:33.646411Z","end":"2026-05-28T22:32:33.993386Z","steps":["trace[1913896277] 'process raft request' (duration: 169.800608ms)","trace[1913896277] 'compare' (duration: 176.503645ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:33.993492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.88577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:33.993525Z","caller":"traceutil/trace.go:171","msg":"trace[1527294113] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:24631; }","duration":"168.94384ms","start":"2026-05-28T22:32:33.824569Z","end":"2026-05-28T22:32:33.993513Z","steps":["trace[1527294113] 'agreement among raft nodes before linearized reading' (duration: 168.838778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.993573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:33.646336Z","time spent":"347.154719ms","remote":"127.0.0.1:43394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:33.993744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.990987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:33.993827Z","caller":"traceutil/trace.go:171","msg":"trace[1018856715] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:24631; }","duration":"116.11986ms","start":"2026-05-28T22:32:33.877689Z","end":"2026-05-28T22:32:33.993809Z","steps":["trace[1018856715] 'agreement among raft nodes before linearized reading' (duration: 115.956687ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:34.530987Z","caller":"traceutil/trace.go:171","msg":"trace[1926813885] transaction","detail":"{read_only:false; response_revision:24635; number_of_response:1; }","duration":"322.414319ms","start":"2026-05-28T22:32:34.20855Z","end":"2026-05-28T22:32:34.530964Z","steps":["trace[1926813885] 'process raft request' (duration: 318.712619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:34.531157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:34.208523Z","time spent":"322.522492ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:34.536532Z","caller":"traceutil/trace.go:171","msg":"trace[1490558675] linearizableReadLoop","detail":"{readStateIndex:25443; appliedIndex:25441; }","duration":"269.827844ms","start":"2026-05-28T22:32:34.266653Z","end":"2026-05-28T22:32:34.536481Z","steps":["trace[1490558675] 'read index received' (duration: 260.518997ms)","trace[1490558675] 'applied index is now lower than readState.Index' (duration: 9.307937ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:34.536776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.115979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-28T22:32:34.53683Z","caller":"traceutil/trace.go:171","msg":"trace[688288393] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24636; }","duration":"270.204631ms","start":"2026-05-28T22:32:34.266613Z","end":"2026-05-28T22:32:34.536818Z","steps":["trace[688288393] 'agreement among raft nodes before linearized reading' (duration: 269.974897ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:34.536554Z","caller":"traceutil/trace.go:171","msg":"trace[45099127] transaction","detail":"{read_only:false; response_revision:24636; number_of_response:1; }","duration":"327.770611ms","start":"2026-05-28T22:32:34.208761Z","end":"2026-05-28T22:32:34.536532Z","steps":["trace[45099127] 'process raft request' (duration: 327.612898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:34.537102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:34.208722Z","time spent":"328.335212ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:34.744983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.854223ms","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-28T22:32:34.745049Z","caller":"traceutil/trace.go:171","msg":"trace[2052151081] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24636; }","duration":"108.971635ms","start":"2026-05-28T22:32:34.636063Z","end":"2026-05-28T22:32:34.745034Z","steps":["trace[2052151081] 'range keys from in-memory index tree' (duration: 108.733851ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:34.875817Z","caller":"traceutil/trace.go:171","msg":"trace[1551003567] linearizableReadLoop","detail":"{readStateIndex:25444; appliedIndex:25443; }","duration":"123.619863ms","start":"2026-05-28T22:32:34.752168Z","end":"2026-05-28T22:32:34.875788Z","steps":["trace[1551003567] 'read index received' (duration: 123.336397ms)","trace[1551003567] 'applied index is now lower than readState.Index' (duration: 282.086µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:34.87592Z","caller":"traceutil/trace.go:171","msg":"trace[1767446824] transaction","detail":"{read_only:false; response_revision:24637; number_of_response:1; }","duration":"126.772862ms","start":"2026-05-28T22:32:34.749103Z","end":"2026-05-28T22:32:34.875876Z","steps":["trace[1767446824] 'process raft request' (duration: 126.495747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:34.876047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.860228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-28T22:32:34.876112Z","caller":"traceutil/trace.go:171","msg":"trace[177069309] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24637; }","duration":"123.94765ms","start":"2026-05-28T22:32:34.752134Z","end":"2026-05-28T22:32:34.876082Z","steps":["trace[177069309] 'agreement among raft nodes before linearized reading' (duration: 123.761396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.199867Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256879384991386056,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T22:32:38.426027Z","caller":"traceutil/trace.go:171","msg":"trace[899334284] transaction","detail":"{read_only:false; response_revision:24658; number_of_response:1; }","duration":"737.644668ms","start":"2026-05-28T22:32:37.688354Z","end":"2026-05-28T22:32:38.425999Z","steps":["trace[899334284] 'process raft request' (duration: 737.334173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.426206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.688328Z","time spent":"737.786491ms","remote":"127.0.0.1:43634","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-28T22:32:38.426654Z","caller":"traceutil/trace.go:171","msg":"trace[606776544] transaction","detail":"{read_only:false; response_revision:24659; number_of_response:1; }","duration":"207.883669ms","start":"2026-05-28T22:32:38.218741Z","end":"2026-05-28T22:32:38.426625Z","steps":["trace[606776544] 'process raft request' (duration: 207.193416ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:38.426808Z","caller":"traceutil/trace.go:171","msg":"trace[807765002] linearizableReadLoop","detail":"{readStateIndex:25466; appliedIndex:25464; }","duration":"727.697521ms","start":"2026-05-28T22:32:37.699083Z","end":"2026-05-28T22:32:38.42678Z","steps":["trace[807765002] 'read index received' (duration: 650.365904ms)","trace[807765002] 'applied index is now lower than readState.Index' (duration: 77.330547ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:38.426869Z","caller":"traceutil/trace.go:171","msg":"trace[1625247267] transaction","detail":"{read_only:false; response_revision:24661; number_of_response:1; }","duration":"206.30328ms","start":"2026-05-28T22:32:38.22053Z","end":"2026-05-28T22:32:38.426833Z","steps":["trace[1625247267] 'process raft request' (duration: 206.108216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.426988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.23029ms","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":"warn","ts":"2026-05-28T22:32:38.427001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.624819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:38.427031Z","caller":"traceutil/trace.go:171","msg":"trace[1940573349] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:24661; }","duration":"443.335292ms","start":"2026-05-28T22:32:37.983683Z","end":"2026-05-28T22:32:38.427018Z","steps":["trace[1940573349] 'agreement among raft nodes before linearized reading' (duration: 443.23553ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:38.42704Z","caller":"traceutil/trace.go:171","msg":"trace[555970627] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:24661; }","duration":"375.717801ms","start":"2026-05-28T22:32:38.051309Z","end":"2026-05-28T22:32:38.427027Z","steps":["trace[555970627] 'agreement among raft nodes before linearized reading' (duration: 375.630559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.42707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:38.051286Z","time spent":"375.777392ms","remote":"127.0.0.1:41026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:32:38.427071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.983662Z","time spent":"443.398773ms","remote":"127.0.0.1:54060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:32:38.427048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.364045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:38.427141Z","caller":"traceutil/trace.go:171","msg":"trace[2091645184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24661; }","duration":"412.521608ms","start":"2026-05-28T22:32:38.014605Z","end":"2026-05-28T22:32:38.427127Z","steps":["trace[2091645184] 'agreement among raft nodes before linearized reading' (duration: 412.347265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.427181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:38.014589Z","time spent":"412.584709ms","remote":"127.0.0.1:43360","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-28T22:32:38.427152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.030916ms","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-28T22:32:38.427294Z","caller":"traceutil/trace.go:171","msg":"trace[550988472] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24661; }","duration":"728.244251ms","start":"2026-05-28T22:32:37.699038Z","end":"2026-05-28T22:32:38.427283Z","steps":["trace[550988472] 'agreement among raft nodes before linearized reading' (duration: 727.854414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.427325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.699018Z","time spent":"728.297911ms","remote":"127.0.0.1:43634","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":"info","ts":"2026-05-28T22:32:38.427551Z","caller":"traceutil/trace.go:171","msg":"trace[1729692619] transaction","detail":"{read_only:false; response_revision:24660; number_of_response:1; }","duration":"206.683977ms","start":"2026-05-28T22:32:38.220006Z","end":"2026-05-28T22:32:38.42669Z","steps":["trace[1729692619] 'process raft request' (duration: 206.523923ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:46.885891Z","caller":"traceutil/trace.go:171","msg":"trace[1962154675] linearizableReadLoop","detail":"{readStateIndex:25595; appliedIndex:25594; }","duration":"305.05785ms","start":"2026-05-28T22:32:46.580816Z","end":"2026-05-28T22:32:46.885874Z","steps":["trace[1962154675] 'read index received' (duration: 304.960149ms)","trace[1962154675] 'applied index is now lower than readState.Index' (duration: 96.931µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:46.885977Z","caller":"traceutil/trace.go:171","msg":"trace[768374005] transaction","detail":"{read_only:false; response_revision:24785; number_of_response:1; }","duration":"335.934076ms","start":"2026-05-28T22:32:46.550038Z","end":"2026-05-28T22:32:46.885972Z","steps":["trace[768374005] 'process raft request' (duration: 335.723092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:46.88605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:46.550018Z","time spent":"335.979727ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:46.886174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.357737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-88d3da56-0ed1-4f41-99b6-5893c9fccf33\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-28T22:32:46.886192Z","caller":"traceutil/trace.go:171","msg":"trace[1630499204] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-88d3da56-0ed1-4f41-99b6-5893c9fccf33; range_end:; response_count:1; response_revision:24785; }","duration":"305.402447ms","start":"2026-05-28T22:32:46.580783Z","end":"2026-05-28T22:32:46.886185Z","steps":["trace[1630499204] 'agreement among raft nodes before linearized reading' (duration: 305.330716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:46.886209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:46.580765Z","time spent":"305.438737ms","remote":"127.0.0.1:43544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-88d3da56-0ed1-4f41-99b6-5893c9fccf33\" "} {"level":"warn","ts":"2026-05-28T22:32:46.88632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.52932ms","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-28T22:32:46.886344Z","caller":"traceutil/trace.go:171","msg":"trace[967133050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24785; }","duration":"277.559471ms","start":"2026-05-28T22:32:46.608769Z","end":"2026-05-28T22:32:46.886329Z","steps":["trace[967133050] 'agreement among raft nodes before linearized reading' (duration: 277.52161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:47.091893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.426982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:47.091994Z","caller":"traceutil/trace.go:171","msg":"trace[546317614] transaction","detail":"{read_only:false; response_revision:24786; number_of_response:1; }","duration":"200.321206ms","start":"2026-05-28T22:32:46.891659Z","end":"2026-05-28T22:32:47.09198Z","steps":["trace[546317614] 'process raft request' (duration: 99.728551ms)","trace[546317614] 'compare' (duration: 100.163837ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:47.091994Z","caller":"traceutil/trace.go:171","msg":"trace[383954394] linearizableReadLoop","detail":"{readStateIndex:25596; appliedIndex:25595; }","duration":"169.549973ms","start":"2026-05-28T22:32:46.922427Z","end":"2026-05-28T22:32:47.091977Z","steps":["trace[383954394] 'read index received' (duration: 68.854165ms)","trace[383954394] 'applied index is now lower than readState.Index' (duration: 100.694488ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:47.092116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.696246ms","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-28T22:32:47.09215Z","caller":"traceutil/trace.go:171","msg":"trace[2011345803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24786; }","duration":"169.766608ms","start":"2026-05-28T22:32:46.922372Z","end":"2026-05-28T22:32:47.092138Z","steps":["trace[2011345803] 'agreement among raft nodes before linearized reading' (duration: 169.647345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:47.092208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.983846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:47.092231Z","caller":"traceutil/trace.go:171","msg":"trace[1713895052] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24786; }","duration":"118.045088ms","start":"2026-05-28T22:32:46.974178Z","end":"2026-05-28T22:32:47.092223Z","steps":["trace[1713895052] 'agreement among raft nodes before linearized reading' (duration: 118.000197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:47.371501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.182734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-88d3da56-0ed1-4f41-99b6-5893c9fccf33\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-28T22:32:47.371613Z","caller":"traceutil/trace.go:171","msg":"trace[392711520] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-88d3da56-0ed1-4f41-99b6-5893c9fccf33; range_end:; response_count:1; response_revision:24787; }","duration":"161.341467ms","start":"2026-05-28T22:32:47.210242Z","end":"2026-05-28T22:32:47.371584Z","steps":["trace[392711520] 'range keys from in-memory index tree' (duration: 160.96727ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:47.372016Z","caller":"traceutil/trace.go:171","msg":"trace[415166851] transaction","detail":"{read_only:false; response_revision:24788; number_of_response:1; }","duration":"153.57846ms","start":"2026-05-28T22:32:47.218397Z","end":"2026-05-28T22:32:47.371975Z","steps":["trace[415166851] 'process raft request' (duration: 99.906263ms)","trace[415166851] 'compare' (duration: 53.427512ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:47.420883Z","caller":"traceutil/trace.go:171","msg":"trace[380437191] linearizableReadLoop","detail":"{readStateIndex:25599; appliedIndex:25597; }","duration":"173.297683ms","start":"2026-05-28T22:32:47.247553Z","end":"2026-05-28T22:32:47.420851Z","steps":["trace[380437191] 'read index received' (duration: 70.766731ms)","trace[380437191] 'applied index is now lower than readState.Index' (duration: 102.528352ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:47.420929Z","caller":"traceutil/trace.go:171","msg":"trace[1846369933] transaction","detail":"{read_only:false; response_revision:24790; number_of_response:1; }","duration":"172.382556ms","start":"2026-05-28T22:32:47.248528Z","end":"2026-05-28T22:32:47.42091Z","steps":["trace[1846369933] 'process raft request' (duration: 172.073381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:47.42113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.537918ms","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-28T22:32:47.421202Z","caller":"traceutil/trace.go:171","msg":"trace[1083445930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24790; }","duration":"173.63691ms","start":"2026-05-28T22:32:47.247544Z","end":"2026-05-28T22:32:47.421181Z","steps":["trace[1083445930] 'agreement among raft nodes before linearized reading' (duration: 173.406065ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:47.420707Z","caller":"traceutil/trace.go:171","msg":"trace[566433917] transaction","detail":"{read_only:false; response_revision:24789; number_of_response:1; }","duration":"180.410559ms","start":"2026-05-28T22:32:47.240264Z","end":"2026-05-28T22:32:47.420675Z","steps":["trace[566433917] 'process raft request' (duration: 180.150754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:47.658034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.11558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:47.658139Z","caller":"traceutil/trace.go:171","msg":"trace[1357804409] linearizableReadLoop","detail":"{readStateIndex:25601; appliedIndex:25600; }","duration":"237.171345ms","start":"2026-05-28T22:32:47.420952Z","end":"2026-05-28T22:32:47.658124Z","steps":["trace[1357804409] 'read index received' (duration: 90.885693ms)","trace[1357804409] 'applied index is now lower than readState.Index' (duration: 146.284412ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:47.65829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.010511ms","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-28T22:32:47.65833Z","caller":"traceutil/trace.go:171","msg":"trace[946971632] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24791; }","duration":"399.054042ms","start":"2026-05-28T22:32:47.259268Z","end":"2026-05-28T22:32:47.658322Z","steps":["trace[946971632] 'agreement among raft nodes before linearized reading' (duration: 398.896329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:47.658354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:47.259222Z","time spent":"399.125893ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T22:32:47.658559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.616275ms","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-28T22:32:47.658583Z","caller":"traceutil/trace.go:171","msg":"trace[1410702017] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24791; }","duration":"397.667536ms","start":"2026-05-28T22:32:47.26091Z","end":"2026-05-28T22:32:47.658577Z","steps":["trace[1410702017] 'agreement among raft nodes before linearized reading' (duration: 397.597394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:47.658601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:47.260903Z","time spent":"397.693846ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-28T22:32:47.659179Z","caller":"traceutil/trace.go:171","msg":"trace[496402223] transaction","detail":"{read_only:false; response_revision:24792; number_of_response:1; }","duration":"232.996576ms","start":"2026-05-28T22:32:47.426161Z","end":"2026-05-28T22:32:47.659158Z","steps":["trace[496402223] 'process raft request' (duration: 232.866693ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:47.659657Z","caller":"traceutil/trace.go:171","msg":"trace[811341360] transaction","detail":"{read_only:false; response_revision:24791; number_of_response:1; }","duration":"279.985126ms","start":"2026-05-28T22:32:47.3789Z","end":"2026-05-28T22:32:47.658885Z","steps":["trace[811341360] 'process raft request' (duration: 132.920939ms)","trace[811341360] 'compare' (duration: 145.936217ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:47.928585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.124241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:47.92894Z","caller":"traceutil/trace.go:171","msg":"trace[419396573] transaction","detail":"{read_only:false; response_revision:24794; number_of_response:1; }","duration":"264.556213ms","start":"2026-05-28T22:32:47.664363Z","end":"2026-05-28T22:32:47.928919Z","steps":["trace[419396573] 'process raft request' (duration: 158.015175ms)","trace[419396573] 'compare' (duration: 105.762534ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:48.303752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.029532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:48.303841Z","caller":"traceutil/trace.go:171","msg":"trace[1235634323] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:24794; }","duration":"180.127634ms","start":"2026-05-28T22:32:48.123689Z","end":"2026-05-28T22:32:48.303817Z","steps":["trace[1235634323] 'count revisions from in-memory index tree' (duration: 179.9279ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:50.684601Z","caller":"traceutil/trace.go:171","msg":"trace[1395398944] transaction","detail":"{read_only:false; response_revision:24812; number_of_response:1; }","duration":"148.100655ms","start":"2026-05-28T22:32:50.536475Z","end":"2026-05-28T22:32:50.684576Z","steps":["trace[1395398944] 'process raft request' (duration: 147.918042ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:50.816139Z","caller":"traceutil/trace.go:171","msg":"trace[545515180] transaction","detail":"{read_only:false; response_revision:24815; number_of_response:1; }","duration":"245.007113ms","start":"2026-05-28T22:32:50.571107Z","end":"2026-05-28T22:32:50.816114Z","steps":["trace[545515180] 'process raft request' (duration: 244.964972ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:50.816185Z","caller":"traceutil/trace.go:171","msg":"trace[892018891] transaction","detail":"{read_only:false; response_revision:24813; number_of_response:1; }","duration":"254.346529ms","start":"2026-05-28T22:32:50.561794Z","end":"2026-05-28T22:32:50.81614Z","steps":["trace[892018891] 'process raft request' (duration: 254.083485ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:50.816228Z","caller":"traceutil/trace.go:171","msg":"trace[1953375538] transaction","detail":"{read_only:false; response_revision:24814; number_of_response:1; }","duration":"245.211216ms","start":"2026-05-28T22:32:50.570958Z","end":"2026-05-28T22:32:50.816169Z","steps":["trace[1953375538] 'process raft request' (duration: 245.076283ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:54.919381Z","caller":"traceutil/trace.go:171","msg":"trace[969854443] transaction","detail":"{read_only:false; response_revision:24858; number_of_response:1; }","duration":"569.588854ms","start":"2026-05-28T22:32:54.349768Z","end":"2026-05-28T22:32:54.919357Z","steps":["trace[969854443] 'process raft request' (duration: 569.446782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:54.919532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.349741Z","time spent":"569.711366ms","remote":"127.0.0.1:43544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10631,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:54.919606Z","caller":"traceutil/trace.go:171","msg":"trace[615188163] linearizableReadLoop","detail":"{readStateIndex:25670; appliedIndex:25670; }","duration":"494.598532ms","start":"2026-05-28T22:32:54.424979Z","end":"2026-05-28T22:32:54.919577Z","steps":["trace[615188163] 'read index received' (duration: 494.582462ms)","trace[615188163] 'applied index is now lower than readState.Index' (duration: 13.32µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:54.919863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.873767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-28T22:32:54.919895Z","caller":"traceutil/trace.go:171","msg":"trace[1400618640] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24858; }","duration":"494.966619ms","start":"2026-05-28T22:32:54.42492Z","end":"2026-05-28T22:32:54.919886Z","steps":["trace[1400618640] 'agreement among raft nodes before linearized reading' (duration: 494.772155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:54.919927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.424898Z","time spent":"495.023131ms","remote":"127.0.0.1:43522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"info","ts":"2026-05-28T22:32:55.12832Z","caller":"traceutil/trace.go:171","msg":"trace[461102417] transaction","detail":"{read_only:false; response_revision:24860; number_of_response:1; }","duration":"425.290249ms","start":"2026-05-28T22:32:54.702994Z","end":"2026-05-28T22:32:55.128284Z","steps":["trace[461102417] 'process raft request' (duration: 425.148747ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:55.128324Z","caller":"traceutil/trace.go:171","msg":"trace[309180299] transaction","detail":"{read_only:false; response_revision:24859; number_of_response:1; }","duration":"562.305136ms","start":"2026-05-28T22:32:54.565977Z","end":"2026-05-28T22:32:55.128282Z","steps":["trace[309180299] 'process raft request' (duration: 561.925019ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:55.128333Z","caller":"traceutil/trace.go:171","msg":"trace[1115218980] transaction","detail":"{read_only:false; response_revision:24861; number_of_response:1; }","duration":"295.855126ms","start":"2026-05-28T22:32:54.832443Z","end":"2026-05-28T22:32:55.128298Z","steps":["trace[1115218980] 'process raft request' (duration: 295.761574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:55.128506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.565954Z","time spent":"562.454149ms","remote":"127.0.0.1:43634","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-28T22:32:55.128618Z","caller":"traceutil/trace.go:171","msg":"trace[945761149] linearizableReadLoop","detail":"{readStateIndex:25675; appliedIndex:25670; }","duration":"208.926939ms","start":"2026-05-28T22:32:54.919664Z","end":"2026-05-28T22:32:55.128591Z","steps":["trace[945761149] 'read index received' (duration: 208.202436ms)","trace[945761149] 'applied index is now lower than readState.Index' (duration: 723.553µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:55.128657Z","caller":"traceutil/trace.go:171","msg":"trace[1845002006] transaction","detail":"{read_only:false; response_revision:24862; number_of_response:1; }","duration":"296.09439ms","start":"2026-05-28T22:32:54.832514Z","end":"2026-05-28T22:32:55.128609Z","steps":["trace[1845002006] 'process raft request' (duration: 295.734724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:55.12878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.241895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:55.128835Z","caller":"traceutil/trace.go:171","msg":"trace[1520097264] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:24863; }","duration":"323.337426ms","start":"2026-05-28T22:32:54.805486Z","end":"2026-05-28T22:32:55.128823Z","steps":["trace[1520097264] 'agreement among raft nodes before linearized reading' (duration: 323.224794ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:55.128705Z","caller":"traceutil/trace.go:171","msg":"trace[61963720] transaction","detail":"{read_only:false; response_revision:24863; number_of_response:1; }","duration":"295.252534ms","start":"2026-05-28T22:32:54.833441Z","end":"2026-05-28T22:32:55.128694Z","steps":["trace[61963720] 'process raft request' (duration: 295.02077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:55.128859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.805466Z","time spent":"323.387117ms","remote":"127.0.0.1:43596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:32:55.12917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.702973Z","time spent":"425.409502ms","remote":"127.0.0.1:43634","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-28T22:32:55.146043Z","caller":"traceutil/trace.go:171","msg":"trace[425042264] transaction","detail":"{read_only:false; response_revision:24864; number_of_response:1; }","duration":"219.922488ms","start":"2026-05-28T22:32:54.926099Z","end":"2026-05-28T22:32:55.146021Z","steps":["trace[425042264] 'process raft request' (duration: 219.416548ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:55.146346Z","caller":"traceutil/trace.go:171","msg":"trace[1920872611] transaction","detail":"{read_only:false; response_revision:24865; number_of_response:1; }","duration":"220.08983ms","start":"2026-05-28T22:32:54.926231Z","end":"2026-05-28T22:32:55.146321Z","steps":["trace[1920872611] 'process raft request' (duration: 219.401197ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:55.146485Z","caller":"traceutil/trace.go:171","msg":"trace[1881928342] transaction","detail":"{read_only:false; response_revision:24866; number_of_response:1; }","duration":"216.737727ms","start":"2026-05-28T22:32:54.929738Z","end":"2026-05-28T22:32:55.146476Z","steps":["trace[1881928342] 'process raft request' (duration: 215.952603ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:55.146628Z","caller":"traceutil/trace.go:171","msg":"trace[737135172] transaction","detail":"{read_only:false; response_revision:24867; number_of_response:1; }","duration":"213.73884ms","start":"2026-05-28T22:32:54.932877Z","end":"2026-05-28T22:32:55.146616Z","steps":["trace[737135172] 'process raft request' (duration: 212.851703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:55.146793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.143125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:55.146841Z","caller":"traceutil/trace.go:171","msg":"trace[1543725803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24867; }","duration":"131.232157ms","start":"2026-05-28T22:32:55.0156Z","end":"2026-05-28T22:32:55.146832Z","steps":["trace[1543725803] 'agreement among raft nodes before linearized reading' (duration: 131.159405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:55.147153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.801355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-88d3da56-0ed1-4f41-99b6-5893c9fccf33\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-28T22:32:55.147247Z","caller":"traceutil/trace.go:171","msg":"trace[600883690] range","detail":"{range_begin:/registry/persistentvolumes/pvc-88d3da56-0ed1-4f41-99b6-5893c9fccf33; range_end:; response_count:1; response_revision:24867; }","duration":"220.937797ms","start":"2026-05-28T22:32:54.926293Z","end":"2026-05-28T22:32:55.147231Z","steps":["trace[600883690] 'agreement among raft nodes before linearized reading' (duration: 220.675341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:55.147279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.323059ms","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-28T22:32:55.147323Z","caller":"traceutil/trace.go:171","msg":"trace[771613142] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24867; }","duration":"227.40956ms","start":"2026-05-28T22:32:54.919902Z","end":"2026-05-28T22:32:55.147311Z","steps":["trace[771613142] 'agreement among raft nodes before linearized reading' (duration: 227.289358ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:07.74219Z","caller":"traceutil/trace.go:171","msg":"trace[601425363] transaction","detail":"{read_only:false; response_revision:24966; number_of_response:1; }","duration":"288.62642ms","start":"2026-05-28T22:33:07.453544Z","end":"2026-05-28T22:33:07.742171Z","steps":["trace[601425363] 'process raft request' (duration: 288.427176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:08.19944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.719317ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-28T22:33:08.199847Z","caller":"traceutil/trace.go:171","msg":"trace[277771436] transaction","detail":"{read_only:false; response_revision:24967; number_of_response:1; }","duration":"713.866008ms","start":"2026-05-28T22:33:07.485948Z","end":"2026-05-28T22:33:08.199814Z","steps":["trace[277771436] 'process raft request' (duration: 713.590803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:08.200051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:07.485925Z","time spent":"714.017131ms","remote":"127.0.0.1:43634","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-28T22:33:08.497036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.162851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:33:08.497151Z","caller":"traceutil/trace.go:171","msg":"trace[114373119] linearizableReadLoop","detail":"{readStateIndex:25783; appliedIndex:25779; }","duration":"891.958592ms","start":"2026-05-28T22:33:07.605174Z","end":"2026-05-28T22:33:08.497133Z","steps":["trace[114373119] 'read index received' (duration: 136.921374ms)","trace[114373119] 'applied index is now lower than readState.Index' (duration: 755.036078ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:08.49725Z","caller":"traceutil/trace.go:171","msg":"trace[358991985] transaction","detail":"{read_only:false; response_revision:24968; number_of_response:1; }","duration":"1.00264619s","start":"2026-05-28T22:33:07.494592Z","end":"2026-05-28T22:33:08.497238Z","steps":["trace[358991985] 'process raft request' (duration: 705.180753ms)","trace[358991985] 'compare' (duration: 296.373007ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:33:08.497307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:07.49458Z","time spent":"1.002699881s","remote":"127.0.0.1:43634","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-28T22:33:08.497754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.573785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:33:08.497803Z","caller":"traceutil/trace.go:171","msg":"trace[1989793557] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:24968; }","duration":"892.626496ms","start":"2026-05-28T22:33:07.605163Z","end":"2026-05-28T22:33:08.497789Z","steps":["trace[1989793557] 'agreement among raft nodes before linearized reading' (duration: 892.531614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:08.497828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:07.605118Z","time spent":"892.703977ms","remote":"127.0.0.1:39734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:33:08.497789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.591915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T22:33:08.497836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.616047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T22:33:08.497884Z","caller":"traceutil/trace.go:171","msg":"trace[671732892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24968; }","duration":"482.719358ms","start":"2026-05-28T22:33:08.015141Z","end":"2026-05-28T22:33:08.49786Z","steps":["trace[671732892] 'agreement among raft nodes before linearized reading' (duration: 482.551095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:08.497927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:08.015127Z","time spent":"482.791709ms","remote":"127.0.0.1:43360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T22:33:08.497927Z","caller":"traceutil/trace.go:171","msg":"trace[588780920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24968; }","duration":"718.7401ms","start":"2026-05-28T22:33:07.779165Z","end":"2026-05-28T22:33:08.497905Z","steps":["trace[588780920] 'agreement among raft nodes before linearized reading' (duration: 718.527096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:08.497918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.300626ms","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-28T22:33:08.498064Z","caller":"traceutil/trace.go:171","msg":"trace[1232569059] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24968; }","duration":"457.51878ms","start":"2026-05-28T22:33:08.040509Z","end":"2026-05-28T22:33:08.498028Z","steps":["trace[1232569059] 'agreement among raft nodes before linearized reading' (duration: 457.077882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:08.497972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:07.779151Z","time spent":"718.809691ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T22:33:08.498131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:08.040484Z","time spent":"457.636082ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-28T22:33:08.49823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.633027ms","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-28T22:33:08.498284Z","caller":"traceutil/trace.go:171","msg":"trace[924785723] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24968; }","duration":"719.77987ms","start":"2026-05-28T22:33:07.778489Z","end":"2026-05-28T22:33:08.498269Z","steps":["trace[924785723] 'agreement among raft nodes before linearized reading' (duration: 718.924134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:08.498322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:07.778471Z","time spent":"719.84272ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-28T22:33:08.782181Z","caller":"traceutil/trace.go:171","msg":"trace[593625884] transaction","detail":"{read_only:false; response_revision:24969; number_of_response:1; }","duration":"279.61731ms","start":"2026-05-28T22:33:08.502532Z","end":"2026-05-28T22:33:08.782149Z","steps":["trace[593625884] 'process raft request' (duration: 212.745382ms)","trace[593625884] 'compare' (duration: 66.526131ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:08.792783Z","caller":"traceutil/trace.go:171","msg":"trace[631390035] transaction","detail":"{read_only:false; response_revision:24970; number_of_response:1; }","duration":"289.559617ms","start":"2026-05-28T22:33:08.503156Z","end":"2026-05-28T22:33:08.792715Z","steps":["trace[631390035] 'process raft request' (duration: 289.354163ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:08.793107Z","caller":"traceutil/trace.go:171","msg":"trace[452582964] transaction","detail":"{read_only:false; response_revision:24971; number_of_response:1; }","duration":"289.493327ms","start":"2026-05-28T22:33:08.503571Z","end":"2026-05-28T22:33:08.793064Z","steps":["trace[452582964] 'process raft request' (duration: 289.099859ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:09.093477Z","caller":"traceutil/trace.go:171","msg":"trace[1869738196] transaction","detail":"{read_only:false; response_revision:24972; number_of_response:1; }","duration":"293.749507ms","start":"2026-05-28T22:33:08.799698Z","end":"2026-05-28T22:33:09.093447Z","steps":["trace[1869738196] 'process raft request' (duration: 279.623959ms)","trace[1869738196] 'compare' (duration: 13.752261ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:09.324792Z","caller":"traceutil/trace.go:171","msg":"trace[1125213067] transaction","detail":"{read_only:false; response_revision:24973; number_of_response:1; }","duration":"129.83515ms","start":"2026-05-28T22:33:09.194878Z","end":"2026-05-28T22:33:09.324714Z","steps":["trace[1125213067] 'process raft request' (duration: 127.777561ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:09.324881Z","caller":"traceutil/trace.go:171","msg":"trace[2081413491] linearizableReadLoop","detail":"{readStateIndex:25791; appliedIndex:25787; }","duration":"105.087872ms","start":"2026-05-28T22:33:09.219763Z","end":"2026-05-28T22:33:09.324851Z","steps":["trace[2081413491] 'read index received' (duration: 102.91435ms)","trace[2081413491] 'applied index is now lower than readState.Index' (duration: 2.172132ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:09.32492Z","caller":"traceutil/trace.go:171","msg":"trace[943458608] transaction","detail":"{read_only:false; response_revision:24975; number_of_response:1; }","duration":"117.948666ms","start":"2026-05-28T22:33:09.206934Z","end":"2026-05-28T22:33:09.324882Z","steps":["trace[943458608] 'process raft request' (duration: 117.827802ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:09.325027Z","caller":"traceutil/trace.go:171","msg":"trace[1776716450] transaction","detail":"{read_only:false; response_revision:24976; number_of_response:1; }","duration":"117.639829ms","start":"2026-05-28T22:33:09.207375Z","end":"2026-05-28T22:33:09.325015Z","steps":["trace[1776716450] 'process raft request' (duration: 117.433044ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:09.325113Z","caller":"traceutil/trace.go:171","msg":"trace[928975750] transaction","detail":"{read_only:false; response_revision:24974; number_of_response:1; }","duration":"118.254571ms","start":"2026-05-28T22:33:09.206825Z","end":"2026-05-28T22:33:09.32508Z","steps":["trace[928975750] 'process raft request' (duration: 117.792482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:09.325488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.700973ms","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-28T22:33:09.325598Z","caller":"traceutil/trace.go:171","msg":"trace[1681330556] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24976; }","duration":"105.879106ms","start":"2026-05-28T22:33:09.2197Z","end":"2026-05-28T22:33:09.325579Z","steps":["trace[1681330556] 'agreement among raft nodes before linearized reading' (duration: 105.591461ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:09.608936Z","caller":"traceutil/trace.go:171","msg":"trace[1607550154] transaction","detail":"{read_only:false; response_revision:24977; number_of_response:1; }","duration":"278.761213ms","start":"2026-05-28T22:33:09.330137Z","end":"2026-05-28T22:33:09.608898Z","steps":["trace[1607550154] 'process raft request' (duration: 278.349106ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:09.64309Z","caller":"traceutil/trace.go:171","msg":"trace[2063908819] transaction","detail":"{read_only:false; response_revision:24979; number_of_response:1; }","duration":"311.571685ms","start":"2026-05-28T22:33:09.331487Z","end":"2026-05-28T22:33:09.643059Z","steps":["trace[2063908819] 'process raft request' (duration: 311.451203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:09.643514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.24101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T22:33:09.643631Z","caller":"traceutil/trace.go:171","msg":"trace[750874691] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:24980; }","duration":"204.462734ms","start":"2026-05-28T22:33:09.439152Z","end":"2026-05-28T22:33:09.643614Z","steps":["trace[750874691] 'agreement among raft nodes before linearized reading' (duration: 204.2053ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:09.643098Z","caller":"traceutil/trace.go:171","msg":"trace[1163534719] transaction","detail":"{read_only:false; response_revision:24978; number_of_response:1; }","duration":"312.295448ms","start":"2026-05-28T22:33:09.330773Z","end":"2026-05-28T22:33:09.643068Z","steps":["trace[1163534719] 'process raft request' (duration: 312.016313ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:09.643234Z","caller":"traceutil/trace.go:171","msg":"trace[973815378] linearizableReadLoop","detail":"{readStateIndex:25795; appliedIndex:25791; }","duration":"203.976996ms","start":"2026-05-28T22:33:09.439221Z","end":"2026-05-28T22:33:09.643198Z","steps":["trace[973815378] 'read index received' (duration: 169.268738ms)","trace[973815378] 'applied index is now lower than readState.Index' (duration: 34.706258ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:33:09.643788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:09.330755Z","time spent":"312.983261ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:33:09.643934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.50985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:33:09.643329Z","caller":"traceutil/trace.go:171","msg":"trace[1181715044] transaction","detail":"{read_only:false; response_revision:24980; number_of_response:1; }","duration":"309.146809ms","start":"2026-05-28T22:33:09.334163Z","end":"2026-05-28T22:33:09.643309Z","steps":["trace[1181715044] 'process raft request' (duration: 308.823633ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:09.643981Z","caller":"traceutil/trace.go:171","msg":"trace[1640572744] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:24980; }","duration":"171.601411ms","start":"2026-05-28T22:33:09.472365Z","end":"2026-05-28T22:33:09.643967Z","steps":["trace[1640572744] 'agreement among raft nodes before linearized reading' (duration: 171.523829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:09.644022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:09.334132Z","time spent":"309.848582ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":925,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:33:09.643672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:09.331464Z","time spent":"312.039913ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:33:09.827207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.972775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:33:09.827322Z","caller":"traceutil/trace.go:171","msg":"trace[635317146] transaction","detail":"{read_only:false; response_revision:24981; number_of_response:1; }","duration":"177.426432ms","start":"2026-05-28T22:33:09.649867Z","end":"2026-05-28T22:33:09.827294Z","steps":["trace[635317146] 'process raft request' (duration: 59.290943ms)","trace[635317146] 'compare' (duration: 117.865074ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:09.82741Z","caller":"traceutil/trace.go:171","msg":"trace[1883751905] linearizableReadLoop","detail":"{readStateIndex:25796; appliedIndex:25795; }","duration":"160.743965ms","start":"2026-05-28T22:33:09.666623Z","end":"2026-05-28T22:33:09.827367Z","steps":["trace[1883751905] 'read index received' (duration: 42.533385ms)","trace[1883751905] 'applied index is now lower than readState.Index' (duration: 118.20756ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:33:09.827588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.92571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T22:33:09.827697Z","caller":"traceutil/trace.go:171","msg":"trace[1308127425] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:24982; }","duration":"161.114663ms","start":"2026-05-28T22:33:09.666555Z","end":"2026-05-28T22:33:09.82767Z","steps":["trace[1308127425] 'agreement among raft nodes before linearized reading' (duration: 160.903559ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:14.373819Z","caller":"traceutil/trace.go:171","msg":"trace[664609220] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"156.18102ms","start":"2026-05-28T22:33:14.217607Z","end":"2026-05-28T22:33:14.373788Z","steps":["trace[664609220] 'process raft request' (duration: 155.979076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:14.555112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.061283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:33:14.555753Z","caller":"traceutil/trace.go:171","msg":"trace[1797482601] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"332.054643ms","start":"2026-05-28T22:33:14.223666Z","end":"2026-05-28T22:33:14.555721Z","steps":["trace[1797482601] 'process raft request' (duration: 180.286026ms)","trace[1797482601] 'compare' (duration: 150.713597ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:14.555782Z","caller":"traceutil/trace.go:171","msg":"trace[1538242683] linearizableReadLoop","detail":"{readStateIndex:25841; appliedIndex:25839; }","duration":"221.549538ms","start":"2026-05-28T22:33:14.334132Z","end":"2026-05-28T22:33:14.555682Z","steps":["trace[1538242683] 'read index received' (duration: 39.498308ms)","trace[1538242683] 'applied index is now lower than readState.Index' (duration: 182.04833ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:33:14.555878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:14.223638Z","time spent":"332.180706ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:33:14.555989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.856805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T22:33:14.556053Z","caller":"traceutil/trace.go:171","msg":"trace[666929282] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:25025; }","duration":"221.967887ms","start":"2026-05-28T22:33:14.334065Z","end":"2026-05-28T22:33:14.556033Z","steps":["trace[666929282] 'agreement among raft nodes before linearized reading' (duration: 221.862905ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:14.633469Z","caller":"traceutil/trace.go:171","msg":"trace[170185717] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"250.472476ms","start":"2026-05-28T22:33:14.382977Z","end":"2026-05-28T22:33:14.63345Z","steps":["trace[170185717] 'process raft request' (duration: 250.361284ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:14.693128Z","caller":"traceutil/trace.go:171","msg":"trace[28958448] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"170.899698ms","start":"2026-05-28T22:33:14.522199Z","end":"2026-05-28T22:33:14.693099Z","steps":["trace[28958448] 'process raft request' (duration: 168.981231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:14.952317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.801976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-28T22:33:14.952377Z","caller":"traceutil/trace.go:171","msg":"trace[1844291220] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25027; }","duration":"132.897429ms","start":"2026-05-28T22:33:14.819464Z","end":"2026-05-28T22:33:14.952362Z","steps":["trace[1844291220] 'range keys from in-memory index tree' (duration: 132.643794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:14.952521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.241392ms","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-28T22:33:14.952582Z","caller":"traceutil/trace.go:171","msg":"trace[750378062] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25027; }","duration":"145.350914ms","start":"2026-05-28T22:33:14.807216Z","end":"2026-05-28T22:33:14.952567Z","steps":["trace[750378062] 'range keys from in-memory index tree' (duration: 145.08063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:14.952623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.505099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T22:33:14.952701Z","caller":"traceutil/trace.go:171","msg":"trace[442570224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25027; }","duration":"134.630181ms","start":"2026-05-28T22:33:14.818052Z","end":"2026-05-28T22:33:14.952682Z","steps":["trace[442570224] 'range keys from in-memory index tree' (duration: 134.347756ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:19.181574Z","caller":"traceutil/trace.go:171","msg":"trace[1122937155] transaction","detail":"{read_only:false; response_revision:25057; number_of_response:1; }","duration":"127.225341ms","start":"2026-05-28T22:33:19.054319Z","end":"2026-05-28T22:33:19.181544Z","steps":["trace[1122937155] 'process raft request' (duration: 127.107689ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:19.181729Z","caller":"traceutil/trace.go:171","msg":"trace[1069265037] transaction","detail":"{read_only:false; response_revision:25056; number_of_response:1; }","duration":"127.939864ms","start":"2026-05-28T22:33:19.053778Z","end":"2026-05-28T22:33:19.181718Z","steps":["trace[1069265037] 'process raft request' (duration: 91.948783ms)","trace[1069265037] 'compare' (duration: 35.445741ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:33:24.38792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.771506ms","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-28T22:33:24.388022Z","caller":"traceutil/trace.go:171","msg":"trace[428737422] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:25097; }","duration":"266.958709ms","start":"2026-05-28T22:33:24.121039Z","end":"2026-05-28T22:33:24.387998Z","steps":["trace[428737422] 'count revisions from in-memory index tree' (duration: 266.664373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:24.388208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.799862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-28T22:33:24.388265Z","caller":"traceutil/trace.go:171","msg":"trace[601618503] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25097; }","duration":"289.920254ms","start":"2026-05-28T22:33:24.098332Z","end":"2026-05-28T22:33:24.388252Z","steps":["trace[601618503] 'range keys from in-memory index tree' (duration: 289.596428ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:24.558319Z","caller":"traceutil/trace.go:171","msg":"trace[341154973] transaction","detail":"{read_only:false; response_revision:25098; number_of_response:1; }","duration":"161.415069ms","start":"2026-05-28T22:33:24.396868Z","end":"2026-05-28T22:33:24.558284Z","steps":["trace[341154973] 'process raft request' (duration: 161.160734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:25.033836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.843739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:33:25.033991Z","caller":"traceutil/trace.go:171","msg":"trace[1632285733] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"421.276843ms","start":"2026-05-28T22:33:24.612683Z","end":"2026-05-28T22:33:25.03396Z","steps":["trace[1632285733] 'process raft request' (duration: 147.192639ms)","trace[1632285733] 'compare' (duration: 273.515083ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:33:25.034097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:24.612648Z","time spent":"421.399995ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:33:25.073521Z","caller":"traceutil/trace.go:171","msg":"trace[883262657] transaction","detail":"{read_only:false; response_revision:25100; number_of_response:1; }","duration":"341.368549ms","start":"2026-05-28T22:33:24.731874Z","end":"2026-05-28T22:33:25.073243Z","steps":["trace[883262657] 'process raft request' (duration: 341.201576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:25.073646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:24.731844Z","time spent":"341.741657ms","remote":"127.0.0.1:43634","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-28T22:33:25.370438Z","caller":"traceutil/trace.go:171","msg":"trace[1931252065] linearizableReadLoop","detail":"{readStateIndex:25920; appliedIndex:25919; }","duration":"163.781894ms","start":"2026-05-28T22:33:25.206623Z","end":"2026-05-28T22:33:25.370405Z","steps":["trace[1931252065] 'read index received' (duration: 84.513092ms)","trace[1931252065] 'applied index is now lower than readState.Index' (duration: 79.267462ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:25.37045Z","caller":"traceutil/trace.go:171","msg":"trace[1050562111] transaction","detail":"{read_only:false; response_revision:25102; number_of_response:1; }","duration":"200.698044ms","start":"2026-05-28T22:33:25.169721Z","end":"2026-05-28T22:33:25.370419Z","steps":["trace[1050562111] 'process raft request' (duration: 121.406522ms)","trace[1050562111] 'compare' (duration: 79.005616ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:33:25.370696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.503459ms","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":"warn","ts":"2026-05-28T22:33:25.370707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.059559ms","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-28T22:33:25.370736Z","caller":"traceutil/trace.go:171","msg":"trace[807445301] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25102; }","duration":"163.57038ms","start":"2026-05-28T22:33:25.207152Z","end":"2026-05-28T22:33:25.370722Z","steps":["trace[807445301] 'agreement among raft nodes before linearized reading' (duration: 163.404967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:25.370758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.245754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:33:25.370783Z","caller":"traceutil/trace.go:171","msg":"trace[126458167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25102; }","duration":"164.185351ms","start":"2026-05-28T22:33:25.206579Z","end":"2026-05-28T22:33:25.370765Z","steps":["trace[126458167] 'agreement among raft nodes before linearized reading' (duration: 163.941436ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:25.370829Z","caller":"traceutil/trace.go:171","msg":"trace[729366927] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25102; }","duration":"105.374666ms","start":"2026-05-28T22:33:25.265437Z","end":"2026-05-28T22:33:25.370812Z","steps":["trace[729366927] 'agreement among raft nodes before linearized reading' (duration: 105.236744ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:25.594828Z","caller":"traceutil/trace.go:171","msg":"trace[1066248959] transaction","detail":"{read_only:false; response_revision:25107; number_of_response:1; }","duration":"195.465414ms","start":"2026-05-28T22:33:25.399344Z","end":"2026-05-28T22:33:25.594809Z","steps":["trace[1066248959] 'process raft request' (duration: 195.433594ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:25.595009Z","caller":"traceutil/trace.go:171","msg":"trace[823958422] transaction","detail":"{read_only:false; response_revision:25106; number_of_response:1; }","duration":"195.895062ms","start":"2026-05-28T22:33:25.399107Z","end":"2026-05-28T22:33:25.595002Z","steps":["trace[823958422] 'process raft request' (duration: 195.636777ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:25.595054Z","caller":"traceutil/trace.go:171","msg":"trace[846922165] transaction","detail":"{read_only:false; response_revision:25105; number_of_response:1; }","duration":"219.174834ms","start":"2026-05-28T22:33:25.375848Z","end":"2026-05-28T22:33:25.595023Z","steps":["trace[846922165] 'process raft request' (duration: 218.855519ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:25.595122Z","caller":"traceutil/trace.go:171","msg":"trace[1738970886] transaction","detail":"{read_only:false; response_revision:25104; number_of_response:1; }","duration":"220.198853ms","start":"2026-05-28T22:33:25.374915Z","end":"2026-05-28T22:33:25.595113Z","steps":["trace[1738970886] 'process raft request' (duration: 206.440882ms)","trace[1738970886] 'compare' (duration: 13.245992ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:25.611882Z","caller":"traceutil/trace.go:171","msg":"trace[740241162] transaction","detail":"{read_only:false; response_revision:25109; number_of_response:1; }","duration":"197.409521ms","start":"2026-05-28T22:33:25.414464Z","end":"2026-05-28T22:33:25.611874Z","steps":["trace[740241162] 'process raft request' (duration: 197.37967ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:25.61198Z","caller":"traceutil/trace.go:171","msg":"trace[160089447] transaction","detail":"{read_only:false; response_revision:25108; number_of_response:1; }","duration":"198.962921ms","start":"2026-05-28T22:33:25.412971Z","end":"2026-05-28T22:33:25.611934Z","steps":["trace[160089447] 'process raft request' (duration: 198.832128ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:25.967553Z","caller":"traceutil/trace.go:171","msg":"trace[1478556617] transaction","detail":"{read_only:false; response_revision:25111; number_of_response:1; }","duration":"107.827173ms","start":"2026-05-28T22:33:25.859699Z","end":"2026-05-28T22:33:25.967526Z","steps":["trace[1478556617] 'process raft request' (duration: 107.607099ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:25.968292Z","caller":"traceutil/trace.go:171","msg":"trace[818887352] linearizableReadLoop","detail":"{readStateIndex:25930; appliedIndex:25928; }","duration":"106.236194ms","start":"2026-05-28T22:33:25.862037Z","end":"2026-05-28T22:33:25.968274Z","steps":["trace[818887352] 'read index received' (duration: 105.366637ms)","trace[818887352] 'applied index is now lower than readState.Index' (duration: 868.147µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:33:25.968467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.413507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-28T22:33:25.968447Z","caller":"traceutil/trace.go:171","msg":"trace[290656775] transaction","detail":"{read_only:false; response_revision:25112; number_of_response:1; }","duration":"107.155482ms","start":"2026-05-28T22:33:25.861262Z","end":"2026-05-28T22:33:25.968417Z","steps":["trace[290656775] 'process raft request' (duration: 106.901576ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:25.9685Z","caller":"traceutil/trace.go:171","msg":"trace[1854387371] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25112; }","duration":"106.466358ms","start":"2026-05-28T22:33:25.862025Z","end":"2026-05-28T22:33:25.968492Z","steps":["trace[1854387371] 'agreement among raft nodes before linearized reading' (duration: 106.324895ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:31.064275Z","caller":"traceutil/trace.go:171","msg":"trace[642499370] linearizableReadLoop","detail":"{readStateIndex:25961; appliedIndex:25960; }","duration":"207.94404ms","start":"2026-05-28T22:33:30.856291Z","end":"2026-05-28T22:33:31.064235Z","steps":["trace[642499370] 'read index received' (duration: 207.674615ms)","trace[642499370] 'applied index is now lower than readState.Index' (duration: 268.065µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:31.064379Z","caller":"traceutil/trace.go:171","msg":"trace[928652711] transaction","detail":"{read_only:false; response_revision:25142; number_of_response:1; }","duration":"214.180699ms","start":"2026-05-28T22:33:30.850167Z","end":"2026-05-28T22:33:31.064347Z","steps":["trace[928652711] 'process raft request' (duration: 213.857814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:31.064602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.286326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-05-28T22:33:31.064654Z","caller":"traceutil/trace.go:171","msg":"trace[228319016] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:25142; }","duration":"208.396559ms","start":"2026-05-28T22:33:30.85624Z","end":"2026-05-28T22:33:31.064637Z","steps":["trace[228319016] 'agreement among raft nodes before linearized reading' (duration: 208.180675ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:31.320358Z","caller":"traceutil/trace.go:171","msg":"trace[1398292771] linearizableReadLoop","detail":"{readStateIndex:25962; appliedIndex:25961; }","duration":"255.94417ms","start":"2026-05-28T22:33:31.064383Z","end":"2026-05-28T22:33:31.320327Z","steps":["trace[1398292771] 'read index received' (duration: 255.735696ms)","trace[1398292771] 'applied index is now lower than readState.Index' (duration: 207.054µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:31.320386Z","caller":"traceutil/trace.go:171","msg":"trace[175760095] transaction","detail":"{read_only:false; response_revision:25143; number_of_response:1; }","duration":"266.955849ms","start":"2026-05-28T22:33:31.053403Z","end":"2026-05-28T22:33:31.320359Z","steps":["trace[175760095] 'process raft request' (duration: 266.662223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:31.320527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.621373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:33:31.320559Z","caller":"traceutil/trace.go:171","msg":"trace[550791651] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25143; }","duration":"379.705265ms","start":"2026-05-28T22:33:30.940845Z","end":"2026-05-28T22:33:31.320551Z","steps":["trace[550791651] 'agreement among raft nodes before linearized reading' (duration: 379.590693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:31.320581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:30.940828Z","time spent":"379.747666ms","remote":"127.0.0.1:39276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:33:31.320676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.239136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-28T22:33:31.320781Z","caller":"traceutil/trace.go:171","msg":"trace[1785406732] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25143; }","duration":"332.401328ms","start":"2026-05-28T22:33:30.988362Z","end":"2026-05-28T22:33:31.320763Z","steps":["trace[1785406732] 'agreement among raft nodes before linearized reading' (duration: 332.030941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:31.320832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:30.988333Z","time spent":"332.48788ms","remote":"127.0.0.1:43544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-28T22:33:31.333508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.590866ms","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-28T22:33:31.333508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.258886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-7ee47170-d0a7-429f-83a2-39c1d71ab32f\" ","response":"range_response_count:1 size:1318"} {"level":"info","ts":"2026-05-28T22:33:31.333591Z","caller":"traceutil/trace.go:171","msg":"trace[1198935824] range","detail":"{range_begin:/registry/persistentvolumes/pvc-7ee47170-d0a7-429f-83a2-39c1d71ab32f; range_end:; response_count:1; response_revision:25144; }","duration":"265.481691ms","start":"2026-05-28T22:33:31.068097Z","end":"2026-05-28T22:33:31.333578Z","steps":["trace[1198935824] 'agreement among raft nodes before linearized reading' (duration: 265.287816ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:31.333568Z","caller":"traceutil/trace.go:171","msg":"trace[838839431] transaction","detail":"{read_only:false; response_revision:25144; number_of_response:1; }","duration":"240.62769ms","start":"2026-05-28T22:33:31.092912Z","end":"2026-05-28T22:33:31.33354Z","steps":["trace[838839431] 'process raft request' (duration: 240.290833ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:31.333589Z","caller":"traceutil/trace.go:171","msg":"trace[341271614] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25144; }","duration":"176.669969ms","start":"2026-05-28T22:33:31.156898Z","end":"2026-05-28T22:33:31.333568Z","steps":["trace[341271614] 'agreement among raft nodes before linearized reading' (duration: 176.472734ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:35:19.29197Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23368} {"level":"info","ts":"2026-05-28T22:35:19.436071Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23368,"took":"139.592265ms","hash":1426300980,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":36470784,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-28T22:35:19.436132Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1426300980,"revision":23368,"compact-revision":20152} {"level":"info","ts":"2026-05-28T22:35:39.966271Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a92.wal"} {"level":"info","ts":"2026-05-28T22:40:19.298292Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26229} {"level":"info","ts":"2026-05-28T22:40:19.444583Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26229,"took":"141.453111ms","hash":263035550,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":33411072,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-28T22:40:19.444668Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":263035550,"revision":26229,"compact-revision":23368} {"level":"info","ts":"2026-05-28T22:40:50.245514Z","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-28T22:40:50.251828Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-28T22:40:50.251954Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-28T22:45:19.304499Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28853} {"level":"info","ts":"2026-05-28T22:45:19.447138Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28853,"took":"137.502467ms","hash":932675498,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":30142464,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-28T22:45:19.44723Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":932675498,"revision":28853,"compact-revision":26229} {"level":"info","ts":"2026-05-28T22:50:19.314025Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31071} {"level":"info","ts":"2026-05-28T22:50:19.440588Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31071,"took":"122.595568ms","hash":3432217139,"current-db-size-bytes":58580992,"current-db-size":"59 MB","current-db-size-in-use-bytes":31023104,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-28T22:50:19.440646Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3432217139,"revision":31071,"compact-revision":28853} {"level":"info","ts":"2026-05-28T22:55:19.319839Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33335} {"level":"info","ts":"2026-05-28T22:55:19.47199Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33335,"took":"148.2567ms","hash":3469213210,"current-db-size-bytes":59621376,"current-db-size":"60 MB","current-db-size-in-use-bytes":32034816,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-28T22:55:19.472054Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3469213210,"revision":33335,"compact-revision":31071} {"level":"info","ts":"2026-05-28T23:00:19.326087Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35633} {"level":"info","ts":"2026-05-28T23:00:19.467614Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35633,"took":"137.853646ms","hash":623735789,"current-db-size-bytes":59621376,"current-db-size":"60 MB","current-db-size-in-use-bytes":31240192,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-28T23:00:19.467677Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":623735789,"revision":35633,"compact-revision":33335} {"level":"info","ts":"2026-05-28T23:02:11.936625Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"56c01ea8fdc90390","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-28T23:02:11.941311Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-28T23:02:11.941426Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}