{"level":"warn","ts":"2026-07-21T21:02:19.869592Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T21:02:19.869691Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.152: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.19.213.152:2380","--initial-cluster=instance=https://199.19.213.152:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.152:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.152: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-07-21T21:02:19.869762Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T21:02:19.869778Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.152:2380"]} {"level":"info","ts":"2026-07-21T21:02:19.869842Z","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-07-21T21:02:19.87085Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.152:2379"]} {"level":"info","ts":"2026-07-21T21:02:19.870942Z","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.19.213.152:2380"],"listen-peer-urls":["https://199.19.213.152:2380"],"advertise-client-urls":["https://199.19.213.152:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.152:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.152: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-07-21T21:02:19.872993Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.739203ms"} {"level":"info","ts":"2026-07-21T21:02:19.876644Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"68d6d88687e6b830","cluster-id":"8a5d84e8c26d99a1"} {"level":"info","ts":"2026-07-21T21:02:19.876717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T21:02:19.876736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became follower at term 0"} {"level":"info","ts":"2026-07-21T21:02:19.876749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 68d6d88687e6b830 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T21:02:19.876759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became follower at term 1"} {"level":"info","ts":"2026-07-21T21:02:19.876799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 switched to configuration voters=(7554463497277847600)"} {"level":"warn","ts":"2026-07-21T21:02:19.880929Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T21:02:19.882567Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T21:02:19.883526Z","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-07-21T21:02:19.885497Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"68d6d88687e6b830","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T21:02:19.885665Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"68d6d88687e6b830","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T21:02:19.885938Z","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-07-21T21:02:19.886107Z","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-07-21T21:02:19.88626Z","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-07-21T21:02:19.888723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 switched to configuration voters=(7554463497277847600)"} {"level":"info","ts":"2026-07-21T21:02:19.888986Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8a5d84e8c26d99a1","local-member-id":"68d6d88687e6b830","added-peer-id":"68d6d88687e6b830","added-peer-peer-urls":["https://199.19.213.152:2380"]} {"level":"info","ts":"2026-07-21T21:02:19.891391Z","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-07-21T21:02:19.891687Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"68d6d88687e6b830","initial-advertise-peer-urls":["https://199.19.213.152:2380"],"listen-peer-urls":["https://199.19.213.152:2380"],"advertise-client-urls":["https://199.19.213.152:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.152:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T21:02:19.89174Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T21:02:19.89184Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.152:2380"} {"level":"info","ts":"2026-07-21T21:02:19.891864Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.152:2380"} {"level":"info","ts":"2026-07-21T21:02:20.677376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T21:02:20.677453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T21:02:20.677477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 received MsgPreVoteResp from 68d6d88687e6b830 at term 1"} {"level":"info","ts":"2026-07-21T21:02:20.677498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became candidate at term 2"} {"level":"info","ts":"2026-07-21T21:02:20.677553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 received MsgVoteResp from 68d6d88687e6b830 at term 2"} {"level":"info","ts":"2026-07-21T21:02:20.677585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became leader at term 2"} {"level":"info","ts":"2026-07-21T21:02:20.6776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 68d6d88687e6b830 elected leader 68d6d88687e6b830 at term 2"} {"level":"info","ts":"2026-07-21T21:02:20.67867Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T21:02:20.679865Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"68d6d88687e6b830","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.152:2379]}","request-path":"/0/members/68d6d88687e6b830/attributes","cluster-id":"8a5d84e8c26d99a1","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T21:02:20.679889Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T21:02:20.680029Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T21:02:20.680216Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T21:02:20.68025Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T21:02:20.680291Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8a5d84e8c26d99a1","local-member-id":"68d6d88687e6b830","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T21:02:20.680419Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T21:02:20.680451Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T21:02:20.683204Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.152:2379"} {"level":"info","ts":"2026-07-21T21:02:20.683317Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-21T21:03:03.570429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.695803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:03:03.570628Z","caller":"traceutil/trace.go:171","msg":"trace[1129128024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"260.910808ms","start":"2026-07-21T21:03:03.309684Z","end":"2026-07-21T21:03:03.570595Z","steps":["trace[1129128024] 'range keys from in-memory index tree' (duration: 260.612491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:03:04.059456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.505657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T21:03:04.059651Z","caller":"traceutil/trace.go:171","msg":"trace[521336431] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"326.190869ms","start":"2026-07-21T21:03:03.733445Z","end":"2026-07-21T21:03:04.059635Z","steps":["trace[521336431] 'read index received' (duration: 134.914818ms)","trace[521336431] 'applied index is now lower than readState.Index' (duration: 191.275111ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:03:04.059737Z","caller":"traceutil/trace.go:171","msg":"trace[529077095] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"457.837805ms","start":"2026-07-21T21:03:03.601889Z","end":"2026-07-21T21:03:04.059727Z","steps":["trace[529077095] 'process raft request' (duration: 266.500452ms)","trace[529077095] 'compare' (duration: 190.356224ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:03:04.059807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:03:03.601866Z","time spent":"457.908136ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:03:04.059916Z","caller":"traceutil/trace.go:171","msg":"trace[1747564148] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"413.127574ms","start":"2026-07-21T21:03:03.646747Z","end":"2026-07-21T21:03:04.059875Z","steps":["trace[1747564148] 'process raft request' (duration: 412.830088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:03:04.060071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:03:03.646717Z","time spent":"413.288287ms","remote":"127.0.0.1:33964","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-07-21T21:03:04.059922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.465274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.152\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T21:03:04.060168Z","caller":"traceutil/trace.go:171","msg":"trace[1554293319] range","detail":"{range_begin:/registry/masterleases/199.19.213.152; range_end:; response_count:1; response_revision:591; }","duration":"326.799441ms","start":"2026-07-21T21:03:03.733346Z","end":"2026-07-21T21:03:04.060145Z","steps":["trace[1554293319] 'agreement among raft nodes before linearized reading' (duration: 326.460084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:03:04.060213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:03:03.733321Z","time spent":"326.880632ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.152\" "} {"level":"warn","ts":"2026-07-21T21:03:18.964381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.798631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-21T21:03:18.964466Z","caller":"traceutil/trace.go:171","msg":"trace[1596026236] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:752; }","duration":"150.937714ms","start":"2026-07-21T21:03:18.813511Z","end":"2026-07-21T21:03:18.964448Z","steps":["trace[1596026236] 'range keys from in-memory index tree' (duration: 150.584377ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:04:29.153388Z","caller":"traceutil/trace.go:171","msg":"trace[1919795000] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"122.43668ms","start":"2026-07-21T21:04:29.030922Z","end":"2026-07-21T21:04:29.153359Z","steps":["trace[1919795000] 'process raft request' (duration: 122.150445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:04:29.153616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.837329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3f92585a-6d65-4778-b83f-9a28d7c186d8\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-07-21T21:04:29.153673Z","caller":"traceutil/trace.go:171","msg":"trace[1511041335] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3f92585a-6d65-4778-b83f-9a28d7c186d8; range_end:; response_count:1; response_revision:1785; }","duration":"100.931511ms","start":"2026-07-21T21:04:29.052731Z","end":"2026-07-21T21:04:29.153663Z","steps":["trace[1511041335] 'agreement among raft nodes before linearized reading' (duration: 100.774897ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:04:29.153772Z","caller":"traceutil/trace.go:171","msg":"trace[1287218669] linearizableReadLoop","detail":"{readStateIndex:1841; appliedIndex:1839; }","duration":"100.530103ms","start":"2026-07-21T21:04:29.052756Z","end":"2026-07-21T21:04:29.153286Z","steps":["trace[1287218669] 'read index received' (duration: 9.735015ms)","trace[1287218669] 'applied index is now lower than readState.Index' (duration: 90.793668ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:05:10.539367Z","caller":"traceutil/trace.go:171","msg":"trace[1046878985] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"122.068773ms","start":"2026-07-21T21:05:10.417268Z","end":"2026-07-21T21:05:10.539337Z","steps":["trace[1046878985] 'process raft request' (duration: 28.746177ms)","trace[1046878985] 'compare' (duration: 93.062452ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:05:11.25179Z","caller":"traceutil/trace.go:171","msg":"trace[2103639852] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"127.180841ms","start":"2026-07-21T21:05:11.124582Z","end":"2026-07-21T21:05:11.251763Z","steps":["trace[2103639852] 'process raft request' (duration: 123.257306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:05:48.73244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.815218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-07-21T21:05:48.732533Z","caller":"traceutil/trace.go:171","msg":"trace[34527020] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2428; }","duration":"158.961811ms","start":"2026-07-21T21:05:48.573552Z","end":"2026-07-21T21:05:48.732514Z","steps":["trace[34527020] 'range keys from in-memory index tree' (duration: 158.621754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:05:48.732516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.550675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-07-21T21:05:48.732607Z","caller":"traceutil/trace.go:171","msg":"trace[1389520355] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2428; }","duration":"113.691238ms","start":"2026-07-21T21:05:48.618894Z","end":"2026-07-21T21:05:48.732585Z","steps":["trace[1389520355] 'range keys from in-memory index tree' (duration: 113.366882ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:07:30.784293Z","caller":"traceutil/trace.go:171","msg":"trace[836330821] transaction","detail":"{read_only:false; response_revision:3293; number_of_response:1; }","duration":"121.884624ms","start":"2026-07-21T21:07:30.662372Z","end":"2026-07-21T21:07:30.784257Z","steps":["trace[836330821] 'process raft request' (duration: 65.574241ms)","trace[836330821] 'compare' (duration: 56.070268ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:08:52.13497Z","caller":"traceutil/trace.go:171","msg":"trace[316909289] transaction","detail":"{read_only:false; response_revision:4581; number_of_response:1; }","duration":"189.203357ms","start":"2026-07-21T21:08:51.945737Z","end":"2026-07-21T21:08:52.13494Z","steps":["trace[316909289] 'process raft request' (duration: 110.665129ms)","trace[316909289] 'compare' (duration: 78.277703ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:09:03.620363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.109877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T21:09:03.620463Z","caller":"traceutil/trace.go:171","msg":"trace[1956090844] linearizableReadLoop","detail":"{readStateIndex:4982; appliedIndex:4981; }","duration":"165.521437ms","start":"2026-07-21T21:09:03.454926Z","end":"2026-07-21T21:09:03.620447Z","steps":["trace[1956090844] 'read index received' (duration: 71.192µs)","trace[1956090844] 'applied index is now lower than readState.Index' (duration: 165.449425ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:09:03.620501Z","caller":"traceutil/trace.go:171","msg":"trace[440572660] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"303.296644ms","start":"2026-07-21T21:09:03.317176Z","end":"2026-07-21T21:09:03.620473Z","steps":["trace[440572660] 'process raft request' (duration: 63.010712ms)","trace[440572660] 'compare' (duration: 240.011476ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:09:03.620565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.649669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-07-21T21:09:03.620586Z","caller":"traceutil/trace.go:171","msg":"trace[1349667244] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4779; }","duration":"165.67227ms","start":"2026-07-21T21:09:03.454907Z","end":"2026-07-21T21:09:03.620579Z","steps":["trace[1349667244] 'agreement among raft nodes before linearized reading' (duration: 165.579348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:09:03.620617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:09:03.317154Z","time spent":"303.410035ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9754,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:09:03.989413Z","caller":"traceutil/trace.go:171","msg":"trace[1578072238] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"356.045128ms","start":"2026-07-21T21:09:03.633344Z","end":"2026-07-21T21:09:03.989389Z","steps":["trace[1578072238] 'process raft request' (duration: 311.480758ms)","trace[1578072238] 'compare' (duration: 44.316826ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:09:03.989525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:09:03.633323Z","time spent":"356.14621ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9564,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:09:04.0107Z","caller":"traceutil/trace.go:171","msg":"trace[1584603373] transaction","detail":"{read_only:false; response_revision:4781; number_of_response:1; }","duration":"335.364284ms","start":"2026-07-21T21:09:03.675308Z","end":"2026-07-21T21:09:04.010673Z","steps":["trace[1584603373] 'process raft request' (duration: 335.092329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:09:04.011099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:09:03.675279Z","time spent":"335.505647ms","remote":"127.0.0.1:34206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16428,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:09:04.011213Z","caller":"traceutil/trace.go:171","msg":"trace[1064340233] transaction","detail":"{read_only:false; response_revision:4782; number_of_response:1; }","duration":"334.732482ms","start":"2026-07-21T21:09:03.676437Z","end":"2026-07-21T21:09:04.01117Z","steps":["trace[1064340233] 'process raft request' (duration: 334.178131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:09:04.011463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:09:03.676428Z","time spent":"334.906916ms","remote":"127.0.0.1:33890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16276,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:09:04.011448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.128721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.152\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T21:09:04.011561Z","caller":"traceutil/trace.go:171","msg":"trace[2100863187] range","detail":"{range_begin:/registry/masterleases/199.19.213.152; range_end:; response_count:1; response_revision:4782; }","duration":"260.275203ms","start":"2026-07-21T21:09:03.751261Z","end":"2026-07-21T21:09:04.011536Z","steps":["trace[2100863187] 'agreement among raft nodes before linearized reading' (duration: 260.022538ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:09:04.011736Z","caller":"traceutil/trace.go:171","msg":"trace[832868375] linearizableReadLoop","detail":"{readStateIndex:4985; appliedIndex:4982; }","duration":"259.809255ms","start":"2026-07-21T21:09:03.751289Z","end":"2026-07-21T21:09:04.011098Z","steps":["trace[832868375] 'read index received' (duration: 193.53258ms)","trace[832868375] 'applied index is now lower than readState.Index' (duration: 66.274825ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:09:04.023204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.858652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-21T21:09:04.023293Z","caller":"traceutil/trace.go:171","msg":"trace[992577099] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4785; }","duration":"241.999094ms","start":"2026-07-21T21:09:03.781275Z","end":"2026-07-21T21:09:04.023274Z","steps":["trace[992577099] 'agreement among raft nodes before linearized reading' (duration: 241.77423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:09:04.02357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.241353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-07-21T21:09:04.023611Z","caller":"traceutil/trace.go:171","msg":"trace[94329055] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4785; }","duration":"227.318285ms","start":"2026-07-21T21:09:03.796279Z","end":"2026-07-21T21:09:04.023597Z","steps":["trace[94329055] 'agreement among raft nodes before linearized reading' (duration: 227.226603ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:09:10.61501Z","caller":"traceutil/trace.go:171","msg":"trace[148259108] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"107.870917ms","start":"2026-07-21T21:09:10.507102Z","end":"2026-07-21T21:09:10.614973Z","steps":["trace[148259108] 'process raft request' (duration: 78.870343ms)","trace[148259108] 'compare' (duration: 28.736959ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:12:20.708574Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3227} {"level":"info","ts":"2026-07-21T21:12:20.791508Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3227,"took":"81.360551ms","hash":3251302755,"current-db-size-bytes":61538304,"current-db-size":"62 MB","current-db-size-in-use-bytes":33980416,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-21T21:12:20.791576Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3251302755,"revision":3227,"compact-revision":-1} {"level":"info","ts":"2026-07-21T21:14:54.67019Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002102.wal"} {"level":"info","ts":"2026-07-21T21:15:00.509793Z","caller":"traceutil/trace.go:171","msg":"trace[763911517] linearizableReadLoop","detail":"{readStateIndex:8494; appliedIndex:8493; }","duration":"115.405469ms","start":"2026-07-21T21:15:00.394366Z","end":"2026-07-21T21:15:00.509771Z","steps":["trace[763911517] 'read index received' (duration: 115.177515ms)","trace[763911517] 'applied index is now lower than readState.Index' (duration: 227.034µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:15:00.509862Z","caller":"traceutil/trace.go:171","msg":"trace[745598213] transaction","detail":"{read_only:false; response_revision:8133; number_of_response:1; }","duration":"173.575339ms","start":"2026-07-21T21:15:00.336263Z","end":"2026-07-21T21:15:00.509839Z","steps":["trace[745598213] 'process raft request' (duration: 173.370896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:15:00.51005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.639704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T21:15:00.510237Z","caller":"traceutil/trace.go:171","msg":"trace[291337196] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8133; }","duration":"115.893599ms","start":"2026-07-21T21:15:00.394311Z","end":"2026-07-21T21:15:00.510204Z","steps":["trace[291337196] 'agreement among raft nodes before linearized reading' (duration: 115.590943ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:15:10.556372Z","caller":"traceutil/trace.go:171","msg":"trace[17282709] transaction","detail":"{read_only:false; response_revision:8201; number_of_response:1; }","duration":"238.586249ms","start":"2026-07-21T21:15:10.317756Z","end":"2026-07-21T21:15:10.556342Z","steps":["trace[17282709] 'process raft request' (duration: 202.4427ms)","trace[17282709] 'compare' (duration: 36.012907ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:15:10.696195Z","caller":"traceutil/trace.go:171","msg":"trace[1447190943] transaction","detail":"{read_only:false; response_revision:8203; number_of_response:1; }","duration":"122.490736ms","start":"2026-07-21T21:15:10.573672Z","end":"2026-07-21T21:15:10.696163Z","steps":["trace[1447190943] 'process raft request' (duration: 117.901738ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:15:10.700358Z","caller":"traceutil/trace.go:171","msg":"trace[2093222224] transaction","detail":"{read_only:false; response_revision:8204; number_of_response:1; }","duration":"126.624625ms","start":"2026-07-21T21:15:10.573705Z","end":"2026-07-21T21:15:10.70033Z","steps":["trace[2093222224] 'process raft request' (duration: 126.472311ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:15:38.899927Z","caller":"traceutil/trace.go:171","msg":"trace[148329759] transaction","detail":"{read_only:false; response_revision:8439; number_of_response:1; }","duration":"234.742756ms","start":"2026-07-21T21:15:38.66516Z","end":"2026-07-21T21:15:38.899903Z","steps":["trace[148329759] 'process raft request' (duration: 234.566063ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:15:39.058631Z","caller":"traceutil/trace.go:171","msg":"trace[356120422] transaction","detail":"{read_only:false; response_revision:8440; number_of_response:1; }","duration":"374.48352ms","start":"2026-07-21T21:15:38.684122Z","end":"2026-07-21T21:15:39.058606Z","steps":["trace[356120422] 'process raft request' (duration: 374.258346ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:15:39.058693Z","caller":"traceutil/trace.go:171","msg":"trace[1287771007] transaction","detail":"{read_only:false; response_revision:8441; number_of_response:1; }","duration":"307.31722ms","start":"2026-07-21T21:15:38.751344Z","end":"2026-07-21T21:15:39.058662Z","steps":["trace[1287771007] 'process raft request' (duration: 307.193337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:15:39.058752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:15:38.684087Z","time spent":"374.604203ms","remote":"127.0.0.1:34206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16428,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:15:39.058866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:15:38.751325Z","time spent":"307.449852ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:15:39.060241Z","caller":"traceutil/trace.go:171","msg":"trace[347159818] linearizableReadLoop","detail":"{readStateIndex:8817; appliedIndex:8815; }","duration":"368.320723ms","start":"2026-07-21T21:15:38.691902Z","end":"2026-07-21T21:15:39.060223Z","steps":["trace[347159818] 'read index received' (duration: 207.959855ms)","trace[347159818] 'applied index is now lower than readState.Index' (duration: 160.359798ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:15:39.060406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.644275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:15:39.060448Z","caller":"traceutil/trace.go:171","msg":"trace[1882382174] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:8441; }","duration":"180.725476ms","start":"2026-07-21T21:15:38.879712Z","end":"2026-07-21T21:15:39.060437Z","steps":["trace[1882382174] 'agreement among raft nodes before linearized reading' (duration: 180.652094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:15:39.06045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.534766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-c9f569dcc-w7p9h.18c46a89047b700a\" ","response":"range_response_count:1 size:1132"} {"level":"info","ts":"2026-07-21T21:15:39.060512Z","caller":"traceutil/trace.go:171","msg":"trace[768887701] range","detail":"{range_begin:/registry/events/openstack/barbican-api-c9f569dcc-w7p9h.18c46a89047b700a; range_end:; response_count:1; response_revision:8441; }","duration":"368.634649ms","start":"2026-07-21T21:15:38.691864Z","end":"2026-07-21T21:15:39.060498Z","steps":["trace[768887701] 'agreement among raft nodes before linearized reading' (duration: 368.459135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:15:39.060497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.580219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-21T21:15:39.060552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:15:38.691846Z","time spent":"368.694259ms","remote":"127.0.0.1:33762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1155,"request content":"key:\"/registry/events/openstack/barbican-api-c9f569dcc-w7p9h.18c46a89047b700a\" "} {"level":"info","ts":"2026-07-21T21:15:39.060568Z","caller":"traceutil/trace.go:171","msg":"trace[140156067] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8441; }","duration":"217.696872ms","start":"2026-07-21T21:15:38.84285Z","end":"2026-07-21T21:15:39.060547Z","steps":["trace[140156067] 'agreement among raft nodes before linearized reading' (duration: 217.516728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:15:39.06055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.510439ms","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-07-21T21:15:39.061002Z","caller":"traceutil/trace.go:171","msg":"trace[1840159564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8441; }","duration":"215.985247ms","start":"2026-07-21T21:15:38.844995Z","end":"2026-07-21T21:15:39.06098Z","steps":["trace[1840159564] 'agreement among raft nodes before linearized reading' (duration: 215.424347ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:15:52.517273Z","caller":"traceutil/trace.go:171","msg":"trace[1263528650] transaction","detail":"{read_only:false; response_revision:8577; number_of_response:1; }","duration":"177.780769ms","start":"2026-07-21T21:15:52.339451Z","end":"2026-07-21T21:15:52.517232Z","steps":["trace[1263528650] 'process raft request' (duration: 96.724874ms)","trace[1263528650] 'compare' (duration: 80.734439ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:15:52.559415Z","caller":"traceutil/trace.go:171","msg":"trace[1309280718] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"143.608679ms","start":"2026-07-21T21:15:52.41578Z","end":"2026-07-21T21:15:52.559388Z","steps":["trace[1309280718] 'process raft request' (duration: 143.419416ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:15:53.249921Z","caller":"traceutil/trace.go:171","msg":"trace[2037597478] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"108.855696ms","start":"2026-07-21T21:15:53.141037Z","end":"2026-07-21T21:15:53.249892Z","steps":["trace[2037597478] 'process raft request' (duration: 108.657192ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:15:53.250304Z","caller":"traceutil/trace.go:171","msg":"trace[972279281] transaction","detail":"{read_only:false; response_revision:8582; number_of_response:1; }","duration":"108.281124ms","start":"2026-07-21T21:15:53.142005Z","end":"2026-07-21T21:15:53.250286Z","steps":["trace[972279281] 'process raft request' (duration: 107.848426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:15:53.383528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.152498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T21:15:53.384007Z","caller":"traceutil/trace.go:171","msg":"trace[1491169900] transaction","detail":"{read_only:false; response_revision:8583; number_of_response:1; }","duration":"128.594512ms","start":"2026-07-21T21:15:53.255377Z","end":"2026-07-21T21:15:53.383971Z","steps":["trace[1491169900] 'process raft request' (duration: 14.876054ms)","trace[1491169900] 'compare' (duration: 112.974194ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:15:53.60187Z","caller":"traceutil/trace.go:171","msg":"trace[1291728759] linearizableReadLoop","detail":"{readStateIndex:8971; appliedIndex:8970; }","duration":"144.535536ms","start":"2026-07-21T21:15:53.457254Z","end":"2026-07-21T21:15:53.601789Z","steps":["trace[1291728759] 'read index received' (duration: 122.116419ms)","trace[1291728759] 'applied index is now lower than readState.Index' (duration: 22.415267ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:15:53.601906Z","caller":"traceutil/trace.go:171","msg":"trace[96938261] transaction","detail":"{read_only:false; response_revision:8584; number_of_response:1; }","duration":"190.028724ms","start":"2026-07-21T21:15:53.411798Z","end":"2026-07-21T21:15:53.601827Z","steps":["trace[96938261] 'process raft request' (duration: 167.723038ms)","trace[96938261] 'compare' (duration: 21.984779ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:15:53.60225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.981945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-07-21T21:15:53.602343Z","caller":"traceutil/trace.go:171","msg":"trace[403032376] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8584; }","duration":"145.142588ms","start":"2026-07-21T21:15:53.457176Z","end":"2026-07-21T21:15:53.602318Z","steps":["trace[403032376] 'agreement among raft nodes before linearized reading' (duration: 144.775261ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:15:54.126723Z","caller":"traceutil/trace.go:171","msg":"trace[1077271281] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"120.63084ms","start":"2026-07-21T21:15:54.006066Z","end":"2026-07-21T21:15:54.126697Z","steps":["trace[1077271281] 'process raft request' (duration: 120.422736ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:17:20.714967Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6565} {"level":"info","ts":"2026-07-21T21:17:20.82941Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6565,"took":"112.340422ms","hash":303559450,"current-db-size-bytes":61538304,"current-db-size":"62 MB","current-db-size-in-use-bytes":27004928,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-21T21:17:20.829539Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":303559450,"revision":6565,"compact-revision":3227} {"level":"info","ts":"2026-07-21T21:17:31.407116Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"68d6d88687e6b830","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-21T21:17:31.413665Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-21T21:17:31.413778Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-21T21:20:43.655703Z","caller":"traceutil/trace.go:171","msg":"trace[1451488467] linearizableReadLoop","detail":"{readStateIndex:12037; appliedIndex:12036; }","duration":"122.919485ms","start":"2026-07-21T21:20:43.53275Z","end":"2026-07-21T21:20:43.655669Z","steps":["trace[1451488467] 'read index received' (duration: 122.672191ms)","trace[1451488467] 'applied index is now lower than readState.Index' (duration: 245.124µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:20:43.656016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.247572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-07-21T21:20:43.656114Z","caller":"traceutil/trace.go:171","msg":"trace[481023374] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11520; }","duration":"123.395984ms","start":"2026-07-21T21:20:43.5327Z","end":"2026-07-21T21:20:43.656096Z","steps":["trace[481023374] 'agreement among raft nodes before linearized reading' (duration: 123.131189ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:20:43.655782Z","caller":"traceutil/trace.go:171","msg":"trace[631556109] transaction","detail":"{read_only:false; response_revision:11520; number_of_response:1; }","duration":"126.422493ms","start":"2026-07-21T21:20:43.529309Z","end":"2026-07-21T21:20:43.655731Z","steps":["trace[631556109] 'process raft request' (duration: 126.161738ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:22:20.720196Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9494} {"level":"info","ts":"2026-07-21T21:22:20.805562Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9494,"took":"83.274193ms","hash":426708920,"current-db-size-bytes":61538304,"current-db-size":"62 MB","current-db-size-in-use-bytes":28807168,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-21T21:22:20.805634Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":426708920,"revision":9494,"compact-revision":6565} {"level":"info","ts":"2026-07-21T21:26:34.243194Z","caller":"traceutil/trace.go:171","msg":"trace[1171756021] transaction","detail":"{read_only:false; response_revision:14394; number_of_response:1; }","duration":"125.377946ms","start":"2026-07-21T21:26:34.117787Z","end":"2026-07-21T21:26:34.243165Z","steps":["trace[1171756021] 'process raft request' (duration: 125.243284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:27:04.790539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.719495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-07-21T21:27:04.791574Z","caller":"traceutil/trace.go:171","msg":"trace[1999757842] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:14618; }","duration":"179.553211ms","start":"2026-07-21T21:27:04.611759Z","end":"2026-07-21T21:27:04.791312Z","steps":["trace[1999757842] 'range keys from in-memory index tree' (duration: 177.820579ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:27:09.574894Z","caller":"traceutil/trace.go:171","msg":"trace[2135588785] linearizableReadLoop","detail":"{readStateIndex:15333; appliedIndex:15332; }","duration":"153.264299ms","start":"2026-07-21T21:27:09.421598Z","end":"2026-07-21T21:27:09.574863Z","steps":["trace[2135588785] 'read index received' (duration: 152.981014ms)","trace[2135588785] 'applied index is now lower than readState.Index' (duration: 281.835µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:27:09.575279Z","caller":"traceutil/trace.go:171","msg":"trace[1416332472] transaction","detail":"{read_only:false; response_revision:14649; number_of_response:1; }","duration":"178.750566ms","start":"2026-07-21T21:27:09.39651Z","end":"2026-07-21T21:27:09.575261Z","steps":["trace[1416332472] 'process raft request' (duration: 178.159004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:27:09.575738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.125736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-2jv9q\" ","response":"range_response_count:1 size:12599"} {"level":"info","ts":"2026-07-21T21:27:09.575786Z","caller":"traceutil/trace.go:171","msg":"trace[361030365] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-2jv9q; range_end:; response_count:1; response_revision:14649; }","duration":"154.236558ms","start":"2026-07-21T21:27:09.421535Z","end":"2026-07-21T21:27:09.575772Z","steps":["trace[361030365] 'agreement among raft nodes before linearized reading' (duration: 154.022314ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:27:09.833014Z","caller":"traceutil/trace.go:171","msg":"trace[1994770648] transaction","detail":"{read_only:false; response_revision:14650; number_of_response:1; }","duration":"252.2013ms","start":"2026-07-21T21:27:09.580776Z","end":"2026-07-21T21:27:09.832978Z","steps":["trace[1994770648] 'process raft request' (duration: 222.717506ms)","trace[1994770648] 'compare' (duration: 29.340451ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:27:09.906632Z","caller":"traceutil/trace.go:171","msg":"trace[1572574534] linearizableReadLoop","detail":"{readStateIndex:15335; appliedIndex:15333; }","duration":"276.141867ms","start":"2026-07-21T21:27:09.630455Z","end":"2026-07-21T21:27:09.906597Z","steps":["trace[1572574534] 'read index received' (duration: 173.004967ms)","trace[1572574534] 'applied index is now lower than readState.Index' (duration: 103.13578ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:27:09.906774Z","caller":"traceutil/trace.go:171","msg":"trace[10675154] transaction","detail":"{read_only:false; response_revision:14651; number_of_response:1; }","duration":"319.295952ms","start":"2026-07-21T21:27:09.587415Z","end":"2026-07-21T21:27:09.906711Z","steps":["trace[10675154] 'process raft request' (duration: 319.000317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:27:09.90692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.686841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"warn","ts":"2026-07-21T21:27:09.906926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.506525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-21T21:27:09.906969Z","caller":"traceutil/trace.go:171","msg":"trace[764755108] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14651; }","duration":"262.800413ms","start":"2026-07-21T21:27:09.644155Z","end":"2026-07-21T21:27:09.906955Z","steps":["trace[764755108] 'agreement among raft nodes before linearized reading' (duration: 262.620499ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:27:09.906974Z","caller":"traceutil/trace.go:171","msg":"trace[2008895641] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:14651; }","duration":"276.620217ms","start":"2026-07-21T21:27:09.630343Z","end":"2026-07-21T21:27:09.906963Z","steps":["trace[2008895641] 'agreement among raft nodes before linearized reading' (duration: 276.376632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:27:09.907012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:27:09.587368Z","time spent":"319.505127ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12713,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:27:10.116968Z","caller":"traceutil/trace.go:171","msg":"trace[403477198] transaction","detail":"{read_only:false; response_revision:14652; number_of_response:1; }","duration":"204.300034ms","start":"2026-07-21T21:27:09.912615Z","end":"2026-07-21T21:27:10.116915Z","steps":["trace[403477198] 'process raft request' (duration: 197.741069ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:27:10.119653Z","caller":"traceutil/trace.go:171","msg":"trace[1225024003] transaction","detail":"{read_only:false; response_revision:14654; number_of_response:1; }","duration":"205.325064ms","start":"2026-07-21T21:27:09.914315Z","end":"2026-07-21T21:27:10.11964Z","steps":["trace[1225024003] 'process raft request' (duration: 205.291333ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:27:10.119858Z","caller":"traceutil/trace.go:171","msg":"trace[743323652] transaction","detail":"{read_only:false; response_revision:14653; number_of_response:1; }","duration":"205.609149ms","start":"2026-07-21T21:27:09.914239Z","end":"2026-07-21T21:27:10.119848Z","steps":["trace[743323652] 'process raft request' (duration: 205.292613ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:27:20.725318Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12331} {"level":"info","ts":"2026-07-21T21:27:20.818604Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12331,"took":"90.600312ms","hash":1753655704,"current-db-size-bytes":61538304,"current-db-size":"62 MB","current-db-size-in-use-bytes":25686016,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-21T21:27:20.818664Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1753655704,"revision":12331,"compact-revision":9494} {"level":"info","ts":"2026-07-21T21:28:53.176222Z","caller":"traceutil/trace.go:171","msg":"trace[683176388] transaction","detail":"{read_only:false; response_revision:15881; number_of_response:1; }","duration":"118.507652ms","start":"2026-07-21T21:28:53.057672Z","end":"2026-07-21T21:28:53.17618Z","steps":["trace[683176388] 'process raft request' (duration: 63.181616ms)","trace[683176388] 'compare' (duration: 55.162813ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:28:58.534942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.933528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-64jht\" ","response":"range_response_count:1 size:22095"} {"level":"warn","ts":"2026-07-21T21:28:58.534995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.233095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-64jht\" ","response":"range_response_count:1 size:22095"} {"level":"info","ts":"2026-07-21T21:28:58.535053Z","caller":"traceutil/trace.go:171","msg":"trace[1801115709] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-64jht; range_end:; response_count:1; response_revision:15919; }","duration":"110.084042ms","start":"2026-07-21T21:28:58.424945Z","end":"2026-07-21T21:28:58.535029Z","steps":["trace[1801115709] 'range keys from in-memory index tree' (duration: 109.732374ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:28:58.535077Z","caller":"traceutil/trace.go:171","msg":"trace[1394299408] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-64jht; range_end:; response_count:1; response_revision:15919; }","duration":"142.352728ms","start":"2026-07-21T21:28:58.392702Z","end":"2026-07-21T21:28:58.535055Z","steps":["trace[1394299408] 'range keys from in-memory index tree' (duration: 142.035022ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:28:59.018812Z","caller":"traceutil/trace.go:171","msg":"trace[608068264] linearizableReadLoop","detail":"{readStateIndex:16665; appliedIndex:16663; }","duration":"124.136201ms","start":"2026-07-21T21:28:58.894643Z","end":"2026-07-21T21:28:59.018779Z","steps":["trace[608068264] 'read index received' (duration: 44.838757ms)","trace[608068264] 'applied index is now lower than readState.Index' (duration: 79.296204ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:28:59.018927Z","caller":"traceutil/trace.go:171","msg":"trace[1982329313] transaction","detail":"{read_only:false; response_revision:15923; number_of_response:1; }","duration":"125.152549ms","start":"2026-07-21T21:28:58.893734Z","end":"2026-07-21T21:28:59.018887Z","steps":["trace[1982329313] 'process raft request' (duration: 124.865223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:28:59.019151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.491807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-kj75m\" ","response":"range_response_count:1 size:9787"} {"level":"info","ts":"2026-07-21T21:28:59.019241Z","caller":"traceutil/trace.go:171","msg":"trace[56755310] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-kj75m; range_end:; response_count:1; response_revision:15923; }","duration":"124.63979ms","start":"2026-07-21T21:28:58.894582Z","end":"2026-07-21T21:28:59.019221Z","steps":["trace[56755310] 'agreement among raft nodes before linearized reading' (duration: 124.350344ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:28:59.427146Z","caller":"traceutil/trace.go:171","msg":"trace[745824326] transaction","detail":"{read_only:false; response_revision:15926; number_of_response:1; }","duration":"142.902028ms","start":"2026-07-21T21:28:59.284216Z","end":"2026-07-21T21:28:59.427118Z","steps":["trace[745824326] 'process raft request' (duration: 141.227346ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:28:59.429017Z","caller":"traceutil/trace.go:171","msg":"trace[489053724] linearizableReadLoop","detail":"{readStateIndex:16669; appliedIndex:16666; }","duration":"121.417848ms","start":"2026-07-21T21:28:59.307582Z","end":"2026-07-21T21:28:59.429Z","steps":["trace[489053724] 'read index received' (duration: 17.24721ms)","trace[489053724] 'applied index is now lower than readState.Index' (duration: 104.169128ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:28:59.429337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.722303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:28:59.429386Z","caller":"traceutil/trace.go:171","msg":"trace[1703893302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15926; }","duration":"121.841496ms","start":"2026-07-21T21:28:59.30753Z","end":"2026-07-21T21:28:59.429371Z","steps":["trace[1703893302] 'agreement among raft nodes before linearized reading' (duration: 121.558051ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:32:20.733223Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14821} {"level":"info","ts":"2026-07-21T21:32:20.82296Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14821,"took":"87.562569ms","hash":877404737,"current-db-size-bytes":61538304,"current-db-size":"62 MB","current-db-size-in-use-bytes":32276480,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-21T21:32:20.823009Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":877404737,"revision":14821,"compact-revision":12331} {"level":"info","ts":"2026-07-21T21:34:07.110418Z","caller":"traceutil/trace.go:171","msg":"trace[1929277092] transaction","detail":"{read_only:false; response_revision:18724; number_of_response:1; }","duration":"104.297675ms","start":"2026-07-21T21:34:07.006055Z","end":"2026-07-21T21:34:07.110352Z","steps":["trace[1929277092] 'process raft request' (duration: 16.079326ms)","trace[1929277092] 'compare' (duration: 87.978814ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:34:07.50607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.154322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:34:07.506157Z","caller":"traceutil/trace.go:171","msg":"trace[1080072520] linearizableReadLoop","detail":"{readStateIndex:19605; appliedIndex:19604; }","duration":"331.132404ms","start":"2026-07-21T21:34:07.17501Z","end":"2026-07-21T21:34:07.506143Z","steps":["trace[1080072520] 'read index received' (duration: 163.826029ms)","trace[1080072520] 'applied index is now lower than readState.Index' (duration: 167.305365ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:34:07.506261Z","caller":"traceutil/trace.go:171","msg":"trace[1768499483] transaction","detail":"{read_only:false; response_revision:18725; number_of_response:1; }","duration":"390.609267ms","start":"2026-07-21T21:34:07.11564Z","end":"2026-07-21T21:34:07.50625Z","steps":["trace[1768499483] 'process raft request' (duration: 223.20774ms)","trace[1768499483] 'compare' (duration: 167.057491ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:34:07.506314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:34:07.115598Z","time spent":"390.692398ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:34:07.506582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.993912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-07-21T21:34:07.506927Z","caller":"traceutil/trace.go:171","msg":"trace[1083992186] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:18725; }","duration":"248.360109ms","start":"2026-07-21T21:34:07.258512Z","end":"2026-07-21T21:34:07.506872Z","steps":["trace[1083992186] 'agreement among raft nodes before linearized reading' (duration: 247.88063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:34:07.506635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.625664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-m9h8s\" ","response":"range_response_count:1 size:20739"} {"level":"info","ts":"2026-07-21T21:34:07.507208Z","caller":"traceutil/trace.go:171","msg":"trace[1087299823] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-m9h8s; range_end:; response_count:1; response_revision:18725; }","duration":"332.223675ms","start":"2026-07-21T21:34:07.174956Z","end":"2026-07-21T21:34:07.507179Z","steps":["trace[1087299823] 'agreement among raft nodes before linearized reading' (duration: 331.550413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:34:07.507271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:34:07.174934Z","time spent":"332.318096ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20762,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-m9h8s\" "} {"level":"info","ts":"2026-07-21T21:34:07.749932Z","caller":"traceutil/trace.go:171","msg":"trace[1567884980] transaction","detail":"{read_only:false; response_revision:18726; number_of_response:1; }","duration":"232.663989ms","start":"2026-07-21T21:34:07.517222Z","end":"2026-07-21T21:34:07.749886Z","steps":["trace[1567884980] 'process raft request' (duration: 232.376114ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:34:07.810989Z","caller":"traceutil/trace.go:171","msg":"trace[1925394549] linearizableReadLoop","detail":"{readStateIndex:19607; appliedIndex:19605; }","duration":"177.891097ms","start":"2026-07-21T21:34:07.633066Z","end":"2026-07-21T21:34:07.810957Z","steps":["trace[1925394549] 'read index received' (duration: 116.510108ms)","trace[1925394549] 'applied index is now lower than readState.Index' (duration: 61.380009ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:34:07.811113Z","caller":"traceutil/trace.go:171","msg":"trace[664113839] transaction","detail":"{read_only:false; response_revision:18727; number_of_response:1; }","duration":"287.166427ms","start":"2026-07-21T21:34:07.523889Z","end":"2026-07-21T21:34:07.811056Z","steps":["trace[664113839] 'process raft request' (duration: 286.907572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:34:07.811473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.370106ms","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-07-21T21:34:07.811551Z","caller":"traceutil/trace.go:171","msg":"trace[1320739625] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:18727; }","duration":"178.511179ms","start":"2026-07-21T21:34:07.633016Z","end":"2026-07-21T21:34:07.811527Z","steps":["trace[1320739625] 'agreement among raft nodes before linearized reading' (duration: 178.090761ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:34:38.166849Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"68d6d88687e6b830","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-21T21:34:38.172032Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-21T21:34:38.172155Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-21T21:37:20.741165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17858} {"level":"info","ts":"2026-07-21T21:37:20.850186Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17858,"took":"105.659948ms","hash":3597239553,"current-db-size-bytes":61538304,"current-db-size":"62 MB","current-db-size-in-use-bytes":32694272,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-21T21:37:20.850254Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3597239553,"revision":17858,"compact-revision":14821} {"level":"warn","ts":"2026-07-21T21:41:36.122603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.841747ms","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-07-21T21:41:36.122662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.609626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-21T21:41:36.122705Z","caller":"traceutil/trace.go:171","msg":"trace[1639663521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23092; }","duration":"111.028431ms","start":"2026-07-21T21:41:36.011658Z","end":"2026-07-21T21:41:36.122686Z","steps":["trace[1639663521] 'range keys from in-memory index tree' (duration: 110.690224ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:41:36.122743Z","caller":"traceutil/trace.go:171","msg":"trace[112889063] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:23092; }","duration":"259.708437ms","start":"2026-07-21T21:41:35.863017Z","end":"2026-07-21T21:41:36.122725Z","steps":["trace[112889063] 'count revisions from in-memory index tree' (duration: 259.546914ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:41:36.266978Z","caller":"traceutil/trace.go:171","msg":"trace[435911562] transaction","detail":"{read_only:false; response_revision:23093; number_of_response:1; }","duration":"136.501635ms","start":"2026-07-21T21:41:36.13045Z","end":"2026-07-21T21:41:36.266952Z","steps":["trace[435911562] 'process raft request' (duration: 135.819662ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:41:36.853091Z","caller":"traceutil/trace.go:171","msg":"trace[1608601941] transaction","detail":"{read_only:false; response_revision:23095; number_of_response:1; }","duration":"284.811436ms","start":"2026-07-21T21:41:36.568253Z","end":"2026-07-21T21:41:36.853065Z","steps":["trace[1608601941] 'process raft request' (duration: 284.644292ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:41:36.854924Z","caller":"traceutil/trace.go:171","msg":"trace[58883261] linearizableReadLoop","detail":"{readStateIndex:24180; appliedIndex:24178; }","duration":"160.723666ms","start":"2026-07-21T21:41:36.694183Z","end":"2026-07-21T21:41:36.854906Z","steps":["trace[58883261] 'read index received' (duration: 158.687998ms)","trace[58883261] 'applied index is now lower than readState.Index' (duration: 2.034958ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:41:36.854931Z","caller":"traceutil/trace.go:171","msg":"trace[1217697650] transaction","detail":"{read_only:false; response_revision:23096; number_of_response:1; }","duration":"189.592064ms","start":"2026-07-21T21:41:36.665324Z","end":"2026-07-21T21:41:36.854916Z","steps":["trace[1217697650] 'process raft request' (duration: 189.504943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:41:36.85509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.903449ms","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-07-21T21:41:36.855115Z","caller":"traceutil/trace.go:171","msg":"trace[781386167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23096; }","duration":"160.95869ms","start":"2026-07-21T21:41:36.694147Z","end":"2026-07-21T21:41:36.855106Z","steps":["trace[781386167] 'agreement among raft nodes before linearized reading' (duration: 160.853998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:41:36.855115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.560221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-21T21:41:36.855163Z","caller":"traceutil/trace.go:171","msg":"trace[1359918432] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:23096; }","duration":"151.679514ms","start":"2026-07-21T21:41:36.703476Z","end":"2026-07-21T21:41:36.855155Z","steps":["trace[1359918432] 'agreement among raft nodes before linearized reading' (duration: 151.571882ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:41:36.978047Z","caller":"traceutil/trace.go:171","msg":"trace[2075388885] transaction","detail":"{read_only:false; response_revision:23097; number_of_response:1; }","duration":"117.348671ms","start":"2026-07-21T21:41:36.860676Z","end":"2026-07-21T21:41:36.978025Z","steps":["trace[2075388885] 'process raft request' (duration: 116.658058ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:41:36.978409Z","caller":"traceutil/trace.go:171","msg":"trace[1071440109] transaction","detail":"{read_only:false; response_revision:23098; number_of_response:1; }","duration":"117.512545ms","start":"2026-07-21T21:41:36.860872Z","end":"2026-07-21T21:41:36.978384Z","steps":["trace[1071440109] 'process raft request' (duration: 117.382122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:41:37.277963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.595578ms","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-07-21T21:41:37.278099Z","caller":"traceutil/trace.go:171","msg":"trace[1057064955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23098; }","duration":"185.761532ms","start":"2026-07-21T21:41:37.092316Z","end":"2026-07-21T21:41:37.278077Z","steps":["trace[1057064955] 'range keys from in-memory index tree' (duration: 185.413725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:41:37.27809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.369317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"warn","ts":"2026-07-21T21:41:37.278084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.003905ms","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-07-21T21:41:37.278162Z","caller":"traceutil/trace.go:171","msg":"trace[1039799666] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23098; }","duration":"101.490189ms","start":"2026-07-21T21:41:37.176655Z","end":"2026-07-21T21:41:37.278145Z","steps":["trace[1039799666] 'range keys from in-memory index tree' (duration: 101.126263ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:41:37.278183Z","caller":"traceutil/trace.go:171","msg":"trace[105545940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23098; }","duration":"157.215459ms","start":"2026-07-21T21:41:37.120946Z","end":"2026-07-21T21:41:37.278162Z","steps":["trace[105545940] 'range keys from in-memory index tree' (duration: 156.819062ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:41:37.417316Z","caller":"traceutil/trace.go:171","msg":"trace[1759765589] linearizableReadLoop","detail":"{readStateIndex:24183; appliedIndex:24182; }","duration":"135.472836ms","start":"2026-07-21T21:41:37.281815Z","end":"2026-07-21T21:41:37.417288Z","steps":["trace[1759765589] 'read index received' (duration: 135.236091ms)","trace[1759765589] 'applied index is now lower than readState.Index' (duration: 235.425µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:41:37.417795Z","caller":"traceutil/trace.go:171","msg":"trace[1850536954] transaction","detail":"{read_only:false; response_revision:23099; number_of_response:1; }","duration":"136.447683ms","start":"2026-07-21T21:41:37.281314Z","end":"2026-07-21T21:41:37.417761Z","steps":["trace[1850536954] 'process raft request' (duration: 135.802522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:41:37.418179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.348602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-07-21T21:41:37.41827Z","caller":"traceutil/trace.go:171","msg":"trace[1853493037] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23099; }","duration":"136.465554ms","start":"2026-07-21T21:41:37.28178Z","end":"2026-07-21T21:41:37.418245Z","steps":["trace[1853493037] 'agreement among raft nodes before linearized reading' (duration: 136.100578ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:41:37.546433Z","caller":"traceutil/trace.go:171","msg":"trace[1208513029] linearizableReadLoop","detail":"{readStateIndex:24184; appliedIndex:24183; }","duration":"129.012072ms","start":"2026-07-21T21:41:37.417387Z","end":"2026-07-21T21:41:37.546399Z","steps":["trace[1208513029] 'read index received' (duration: 128.809489ms)","trace[1208513029] 'applied index is now lower than readState.Index' (duration: 201.073µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:41:37.546524Z","caller":"traceutil/trace.go:171","msg":"trace[1784632942] transaction","detail":"{read_only:false; response_revision:23100; number_of_response:1; }","duration":"264.301705ms","start":"2026-07-21T21:41:37.282184Z","end":"2026-07-21T21:41:37.546486Z","steps":["trace[1784632942] 'process raft request' (duration: 263.873387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:41:37.546608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.269732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:41:37.546668Z","caller":"traceutil/trace.go:171","msg":"trace[1650104735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23100; }","duration":"236.368154ms","start":"2026-07-21T21:41:37.310285Z","end":"2026-07-21T21:41:37.546653Z","steps":["trace[1650104735] 'agreement among raft nodes before linearized reading' (duration: 236.248902ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:41:37.547252Z","caller":"traceutil/trace.go:171","msg":"trace[974650157] transaction","detail":"{read_only:false; response_revision:23101; number_of_response:1; }","duration":"123.858796ms","start":"2026-07-21T21:41:37.423373Z","end":"2026-07-21T21:41:37.547231Z","steps":["trace[974650157] 'process raft request' (duration: 123.751914ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:42:20.74778Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20915} {"level":"info","ts":"2026-07-21T21:42:20.865768Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20915,"took":"115.136609ms","hash":3115084229,"current-db-size-bytes":61538304,"current-db-size":"62 MB","current-db-size-in-use-bytes":33513472,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-21T21:42:20.865865Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3115084229,"revision":20915,"compact-revision":17858} {"level":"info","ts":"2026-07-21T21:45:03.795306Z","caller":"traceutil/trace.go:171","msg":"trace[980027267] transaction","detail":"{read_only:false; response_revision:25543; number_of_response:1; }","duration":"120.378419ms","start":"2026-07-21T21:45:03.674908Z","end":"2026-07-21T21:45:03.795286Z","steps":["trace[980027267] 'process raft request' (duration: 120.252306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:03.795408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.011237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:45:03.795356Z","caller":"traceutil/trace.go:171","msg":"trace[1979858351] transaction","detail":"{read_only:false; response_revision:25542; number_of_response:1; }","duration":"120.604553ms","start":"2026-07-21T21:45:03.674724Z","end":"2026-07-21T21:45:03.795329Z","steps":["trace[1979858351] 'process raft request' (duration: 120.279626ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:03.795456Z","caller":"traceutil/trace.go:171","msg":"trace[21706828] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25544; }","duration":"105.100109ms","start":"2026-07-21T21:45:03.690343Z","end":"2026-07-21T21:45:03.795443Z","steps":["trace[21706828] 'agreement among raft nodes before linearized reading' (duration: 104.994447ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:03.795287Z","caller":"traceutil/trace.go:171","msg":"trace[1761332234] linearizableReadLoop","detail":"{readStateIndex:26735; appliedIndex:26731; }","duration":"104.889314ms","start":"2026-07-21T21:45:03.690377Z","end":"2026-07-21T21:45:03.795266Z","steps":["trace[1761332234] 'read index received' (duration: 11.684812ms)","trace[1761332234] 'applied index is now lower than readState.Index' (duration: 93.203702ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:03.795603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.20505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:45:03.79567Z","caller":"traceutil/trace.go:171","msg":"trace[89552175] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25544; }","duration":"105.299042ms","start":"2026-07-21T21:45:03.690355Z","end":"2026-07-21T21:45:03.795654Z","steps":["trace[89552175] 'agreement among raft nodes before linearized reading' (duration: 105.156249ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:03.795588Z","caller":"traceutil/trace.go:171","msg":"trace[1940790751] transaction","detail":"{read_only:false; response_revision:25544; number_of_response:1; }","duration":"120.473991ms","start":"2026-07-21T21:45:03.67504Z","end":"2026-07-21T21:45:03.795514Z","steps":["trace[1940790751] 'process raft request' (duration: 120.178086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:03.795811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.046929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1818"} {"level":"info","ts":"2026-07-21T21:45:03.795849Z","caller":"traceutil/trace.go:171","msg":"trace[1460274674] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:25544; }","duration":"103.098171ms","start":"2026-07-21T21:45:03.692741Z","end":"2026-07-21T21:45:03.795839Z","steps":["trace[1460274674] 'agreement among raft nodes before linearized reading' (duration: 102.985259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:03.795895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.61705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-21T21:45:03.795957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.785723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-07-21T21:45:03.796045Z","caller":"traceutil/trace.go:171","msg":"trace[215225241] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25544; }","duration":"104.896904ms","start":"2026-07-21T21:45:03.691127Z","end":"2026-07-21T21:45:03.796024Z","steps":["trace[215225241] 'agreement among raft nodes before linearized reading' (duration: 104.565528ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:03.795974Z","caller":"traceutil/trace.go:171","msg":"trace[1260023541] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25544; }","duration":"104.758522ms","start":"2026-07-21T21:45:03.691194Z","end":"2026-07-21T21:45:03.795953Z","steps":["trace[1260023541] 'agreement among raft nodes before linearized reading' (duration: 104.568369ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:04.827855Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000689c.wal"} {"level":"info","ts":"2026-07-21T21:47:20.759339Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23642} {"level":"info","ts":"2026-07-21T21:47:20.891968Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23642,"took":"128.526033ms","hash":3440823805,"current-db-size-bytes":61538304,"current-db-size":"62 MB","current-db-size-in-use-bytes":43741184,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-07-21T21:47:20.89202Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3440823805,"revision":23642,"compact-revision":20915} {"level":"info","ts":"2026-07-21T21:48:28.176003Z","caller":"traceutil/trace.go:171","msg":"trace[538463862] transaction","detail":"{read_only:false; response_revision:28037; number_of_response:1; }","duration":"110.052513ms","start":"2026-07-21T21:48:28.065927Z","end":"2026-07-21T21:48:28.175979Z","steps":["trace[538463862] 'process raft request' (duration: 109.90474ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:28.391258Z","caller":"traceutil/trace.go:171","msg":"trace[524611633] linearizableReadLoop","detail":"{readStateIndex:29319; appliedIndex:29318; }","duration":"193.144213ms","start":"2026-07-21T21:48:28.198089Z","end":"2026-07-21T21:48:28.391234Z","steps":["trace[524611633] 'read index received' (duration: 179.30806ms)","trace[524611633] 'applied index is now lower than readState.Index' (duration: 13.835293ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:28.391467Z","caller":"traceutil/trace.go:171","msg":"trace[348336808] transaction","detail":"{read_only:false; response_revision:28038; number_of_response:1; }","duration":"207.437064ms","start":"2026-07-21T21:48:28.184021Z","end":"2026-07-21T21:48:28.391458Z","steps":["trace[348336808] 'process raft request' (duration: 193.356716ms)","trace[348336808] 'compare' (duration: 13.579008ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:48:28.391503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.390467ms","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-07-21T21:48:28.391573Z","caller":"traceutil/trace.go:171","msg":"trace[1876176221] 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:28038; }","duration":"193.491339ms","start":"2026-07-21T21:48:28.198063Z","end":"2026-07-21T21:48:28.391554Z","steps":["trace[1876176221] 'agreement among raft nodes before linearized reading' (duration: 193.376846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:28.67307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.922028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:48:28.673351Z","caller":"traceutil/trace.go:171","msg":"trace[2144512669] transaction","detail":"{read_only:false; response_revision:28039; number_of_response:1; }","duration":"455.383508ms","start":"2026-07-21T21:48:28.217913Z","end":"2026-07-21T21:48:28.673297Z","steps":["trace[2144512669] 'process raft request' (duration: 292.098343ms)","trace[2144512669] 'compare' (duration: 162.182354ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:28.673444Z","caller":"traceutil/trace.go:171","msg":"trace[656330356] linearizableReadLoop","detail":"{readStateIndex:29322; appliedIndex:29319; }","duration":"282.109003ms","start":"2026-07-21T21:48:28.391313Z","end":"2026-07-21T21:48:28.673422Z","steps":["trace[656330356] 'read index received' (duration: 118.767118ms)","trace[656330356] 'applied index is now lower than readState.Index' (duration: 163.340695ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:48:28.673544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:28.217898Z","time spent":"455.540351ms","remote":"127.0.0.1:33990","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-07-21T21:48:28.673594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.846394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-21T21:48:28.673669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.625592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-07-21T21:48:28.673736Z","caller":"traceutil/trace.go:171","msg":"trace[1671120672] transaction","detail":"{read_only:false; response_revision:28041; number_of_response:1; }","duration":"339.218119ms","start":"2026-07-21T21:48:28.334503Z","end":"2026-07-21T21:48:28.673721Z","steps":["trace[1671120672] 'process raft request' (duration: 338.840032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:28.673743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.962128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:48:28.673782Z","caller":"traceutil/trace.go:171","msg":"trace[674574402] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:28041; }","duration":"163.0365ms","start":"2026-07-21T21:48:28.510736Z","end":"2026-07-21T21:48:28.673772Z","steps":["trace[674574402] 'agreement among raft nodes before linearized reading' (duration: 162.961158ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:28.673781Z","caller":"traceutil/trace.go:171","msg":"trace[1501809286] transaction","detail":"{read_only:false; response_revision:28040; number_of_response:1; }","duration":"440.655268ms","start":"2026-07-21T21:48:28.2331Z","end":"2026-07-21T21:48:28.673756Z","steps":["trace[1501809286] 'process raft request' (duration: 440.101697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:28.673868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:28.334483Z","time spent":"339.290181ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:48:28.673905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:28.233082Z","time spent":"440.77307ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:48:28.673684Z","caller":"traceutil/trace.go:171","msg":"trace[1128513294] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28041; }","duration":"306.983217ms","start":"2026-07-21T21:48:28.366684Z","end":"2026-07-21T21:48:28.673667Z","steps":["trace[1128513294] 'agreement among raft nodes before linearized reading' (duration: 306.755833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:28.674021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:28.366665Z","time spent":"307.344494ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-21T21:48:28.673735Z","caller":"traceutil/trace.go:171","msg":"trace[336062472] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28041; }","duration":"324.719674ms","start":"2026-07-21T21:48:28.348999Z","end":"2026-07-21T21:48:28.673719Z","steps":["trace[336062472] 'agreement among raft nodes before linearized reading' (duration: 324.545591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:28.674074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:28.348982Z","time spent":"325.082661ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11520,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-21T21:48:28.674218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.918413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-07-21T21:48:28.674264Z","caller":"traceutil/trace.go:171","msg":"trace[2037922211] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:28041; }","duration":"351.004044ms","start":"2026-07-21T21:48:28.323247Z","end":"2026-07-21T21:48:28.674251Z","steps":["trace[2037922211] 'agreement among raft nodes before linearized reading' (duration: 350.841151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:28.674306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:28.323226Z","time spent":"351.072585ms","remote":"127.0.0.1:33808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-07-21T21:48:28.88366Z","caller":"traceutil/trace.go:171","msg":"trace[1252575701] linearizableReadLoop","detail":"{readStateIndex:29323; appliedIndex:29322; }","duration":"204.219943ms","start":"2026-07-21T21:48:28.679398Z","end":"2026-07-21T21:48:28.883618Z","steps":["trace[1252575701] 'read index received' (duration: 130.848008ms)","trace[1252575701] 'applied index is now lower than readState.Index' (duration: 73.370894ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:28.883817Z","caller":"traceutil/trace.go:171","msg":"trace[496639675] transaction","detail":"{read_only:false; response_revision:28042; number_of_response:1; }","duration":"205.827824ms","start":"2026-07-21T21:48:28.67797Z","end":"2026-07-21T21:48:28.883797Z","steps":["trace[496639675] 'process raft request' (duration: 132.323237ms)","trace[496639675] 'compare' (duration: 73.177811ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:48:28.883946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.524331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-c9f569dcc-w7p9h.18c46a89047b700a\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-07-21T21:48:28.883997Z","caller":"traceutil/trace.go:171","msg":"trace[68045444] range","detail":"{range_begin:/registry/events/openstack/barbican-api-c9f569dcc-w7p9h.18c46a89047b700a; range_end:; response_count:1; response_revision:28042; }","duration":"191.600933ms","start":"2026-07-21T21:48:28.692384Z","end":"2026-07-21T21:48:28.883985Z","steps":["trace[68045444] 'agreement among raft nodes before linearized reading' (duration: 191.45909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:28.88417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.775425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-07-21T21:48:28.884189Z","caller":"traceutil/trace.go:171","msg":"trace[905652587] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28042; }","duration":"204.848926ms","start":"2026-07-21T21:48:28.679335Z","end":"2026-07-21T21:48:28.884184Z","steps":["trace[905652587] 'agreement among raft nodes before linearized reading' (duration: 204.778515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:33.29989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.146592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:48:33.30017Z","caller":"traceutil/trace.go:171","msg":"trace[782402154] transaction","detail":"{read_only:false; response_revision:28080; number_of_response:1; }","duration":"244.624552ms","start":"2026-07-21T21:48:33.05551Z","end":"2026-07-21T21:48:33.300134Z","steps":["trace[782402154] 'process raft request' (duration: 122.151853ms)","trace[782402154] 'compare' (duration: 121.709574ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:48:33.56085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.57098ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:48:33.560959Z","caller":"traceutil/trace.go:171","msg":"trace[2106466959] transaction","detail":"{read_only:false; response_revision:28082; number_of_response:1; }","duration":"246.220061ms","start":"2026-07-21T21:48:33.314719Z","end":"2026-07-21T21:48:33.560939Z","steps":["trace[2106466959] 'process raft request' (duration: 113.457007ms)","trace[2106466959] 'compare' (duration: 132.378497ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:33.606839Z","caller":"traceutil/trace.go:171","msg":"trace[1761561806] linearizableReadLoop","detail":"{readStateIndex:29366; appliedIndex:29364; }","duration":"222.402998ms","start":"2026-07-21T21:48:33.384411Z","end":"2026-07-21T21:48:33.606814Z","steps":["trace[1761561806] 'read index received' (duration: 43.775332ms)","trace[1761561806] 'applied index is now lower than readState.Index' (duration: 178.626686ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:48:33.607028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.953632ms","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-07-21T21:48:33.607085Z","caller":"traceutil/trace.go:171","msg":"trace[516800927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28083; }","duration":"119.031123ms","start":"2026-07-21T21:48:33.488018Z","end":"2026-07-21T21:48:33.60705Z","steps":["trace[516800927] 'agreement among raft nodes before linearized reading' (duration: 118.912611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:33.607064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.640223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-07-21T21:48:33.607142Z","caller":"traceutil/trace.go:171","msg":"trace[198202288] transaction","detail":"{read_only:false; response_revision:28083; number_of_response:1; }","duration":"222.709634ms","start":"2026-07-21T21:48:33.384291Z","end":"2026-07-21T21:48:33.607Z","steps":["trace[198202288] 'process raft request' (duration: 222.404848ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:33.607261Z","caller":"traceutil/trace.go:171","msg":"trace[2139337466] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:28083; }","duration":"222.829966ms","start":"2026-07-21T21:48:33.384399Z","end":"2026-07-21T21:48:33.607229Z","steps":["trace[2139337466] 'agreement among raft nodes before linearized reading' (duration: 222.52842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:33.842152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.108117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:48:33.84243Z","caller":"traceutil/trace.go:171","msg":"trace[695339698] transaction","detail":"{read_only:false; response_revision:28086; number_of_response:1; }","duration":"230.458962ms","start":"2026-07-21T21:48:33.611946Z","end":"2026-07-21T21:48:33.842405Z","steps":["trace[695339698] 'process raft request' (duration: 230.399691ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:33.842789Z","caller":"traceutil/trace.go:171","msg":"trace[124391060] transaction","detail":"{read_only:false; response_revision:28084; number_of_response:1; }","duration":"231.242176ms","start":"2026-07-21T21:48:33.611533Z","end":"2026-07-21T21:48:33.842776Z","steps":["trace[124391060] 'process raft request' (duration: 72.235014ms)","trace[124391060] 'compare' (duration: 157.714808ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:33.842923Z","caller":"traceutil/trace.go:171","msg":"trace[253178194] transaction","detail":"{read_only:false; response_revision:28085; number_of_response:1; }","duration":"231.136595ms","start":"2026-07-21T21:48:33.611778Z","end":"2026-07-21T21:48:33.842914Z","steps":["trace[253178194] 'process raft request' (duration: 230.488763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:34.096862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.12716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:48:34.096969Z","caller":"traceutil/trace.go:171","msg":"trace[131251483] linearizableReadLoop","detail":"{readStateIndex:29370; appliedIndex:29369; }","duration":"203.943078ms","start":"2026-07-21T21:48:33.893007Z","end":"2026-07-21T21:48:34.09695Z","steps":["trace[131251483] 'read index received' (duration: 68.548084ms)","trace[131251483] 'applied index is now lower than readState.Index' (duration: 135.393794ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:34.09708Z","caller":"traceutil/trace.go:171","msg":"trace[319748746] transaction","detail":"{read_only:false; response_revision:28087; number_of_response:1; }","duration":"240.822979ms","start":"2026-07-21T21:48:33.856216Z","end":"2026-07-21T21:48:34.097039Z","steps":["trace[319748746] 'process raft request' (duration: 105.431844ms)","trace[319748746] 'compare' (duration: 135.004838ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:48:34.097174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.161532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.152\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-21T21:48:34.097205Z","caller":"traceutil/trace.go:171","msg":"trace[974718488] range","detail":"{range_begin:/registry/masterleases/199.19.213.152; range_end:; response_count:1; response_revision:28087; }","duration":"204.216093ms","start":"2026-07-21T21:48:33.892978Z","end":"2026-07-21T21:48:34.097194Z","steps":["trace[974718488] 'agreement among raft nodes before linearized reading' (duration: 204.06119ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:34.236256Z","caller":"traceutil/trace.go:171","msg":"trace[388065369] transaction","detail":"{read_only:false; response_revision:28088; number_of_response:1; }","duration":"128.390421ms","start":"2026-07-21T21:48:34.107836Z","end":"2026-07-21T21:48:34.236227Z","steps":["trace[388065369] 'process raft request' (duration: 124.522487ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:34.236559Z","caller":"traceutil/trace.go:171","msg":"trace[1372367266] linearizableReadLoop","detail":"{readStateIndex:29372; appliedIndex:29371; }","duration":"128.191057ms","start":"2026-07-21T21:48:34.107931Z","end":"2026-07-21T21:48:34.236122Z","steps":["trace[1372367266] 'read index received' (duration: 124.412645ms)","trace[1372367266] 'applied index is now lower than readState.Index' (duration: 3.776382ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:48:34.236905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.948821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16790"} {"level":"info","ts":"2026-07-21T21:48:34.237038Z","caller":"traceutil/trace.go:171","msg":"trace[1321295094] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:28088; }","duration":"129.126655ms","start":"2026-07-21T21:48:34.107893Z","end":"2026-07-21T21:48:34.237019Z","steps":["trace[1321295094] 'agreement among raft nodes before linearized reading' (duration: 128.711547ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:34.551197Z","caller":"traceutil/trace.go:171","msg":"trace[232119119] linearizableReadLoop","detail":"{readStateIndex:29373; appliedIndex:29372; }","duration":"119.817168ms","start":"2026-07-21T21:48:34.43136Z","end":"2026-07-21T21:48:34.551177Z","steps":["trace[232119119] 'read index received' (duration: 119.624934ms)","trace[232119119] 'applied index is now lower than readState.Index' (duration: 191.384µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:34.551292Z","caller":"traceutil/trace.go:171","msg":"trace[1889096709] transaction","detail":"{read_only:false; response_revision:28089; number_of_response:1; }","duration":"259.471453ms","start":"2026-07-21T21:48:34.291791Z","end":"2026-07-21T21:48:34.551262Z","steps":["trace[1889096709] 'process raft request' (duration: 259.216228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:34.551478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.097592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-07-21T21:48:34.551563Z","caller":"traceutil/trace.go:171","msg":"trace[1797880930] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28089; }","duration":"120.236175ms","start":"2026-07-21T21:48:34.431301Z","end":"2026-07-21T21:48:34.551537Z","steps":["trace[1797880930] 'agreement among raft nodes before linearized reading' (duration: 119.987081ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:34.723912Z","caller":"traceutil/trace.go:171","msg":"trace[1364080590] transaction","detail":"{read_only:false; number_of_response:0; response_revision:28089; }","duration":"161.636004ms","start":"2026-07-21T21:48:34.562253Z","end":"2026-07-21T21:48:34.723889Z","steps":["trace[1364080590] 'process raft request' (duration: 161.546081ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:34.983454Z","caller":"traceutil/trace.go:171","msg":"trace[1525576159] linearizableReadLoop","detail":"{readStateIndex:29378; appliedIndex:29374; }","duration":"258.458194ms","start":"2026-07-21T21:48:34.724978Z","end":"2026-07-21T21:48:34.983436Z","steps":["trace[1525576159] 'read index received' (duration: 258.24032ms)","trace[1525576159] 'applied index is now lower than readState.Index' (duration: 217.284µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:34.983459Z","caller":"traceutil/trace.go:171","msg":"trace[1488505210] transaction","detail":"{read_only:false; response_revision:28090; number_of_response:1; }","duration":"418.477926ms","start":"2026-07-21T21:48:34.564951Z","end":"2026-07-21T21:48:34.983429Z","steps":["trace[1488505210] 'process raft request' (duration: 418.248392ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:34.983538Z","caller":"traceutil/trace.go:171","msg":"trace[1542185890] transaction","detail":"{read_only:false; response_revision:28093; number_of_response:1; }","duration":"292.076753ms","start":"2026-07-21T21:48:34.691455Z","end":"2026-07-21T21:48:34.983532Z","steps":["trace[1542185890] 'process raft request' (duration: 291.957691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:34.983597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.609917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:13486"} {"level":"info","ts":"2026-07-21T21:48:34.9836Z","caller":"traceutil/trace.go:171","msg":"trace[1381106724] transaction","detail":"{read_only:false; response_revision:28092; number_of_response:1; }","duration":"292.972729ms","start":"2026-07-21T21:48:34.69058Z","end":"2026-07-21T21:48:34.983553Z","steps":["trace[1381106724] 'process raft request' (duration: 292.802266ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:34.983653Z","caller":"traceutil/trace.go:171","msg":"trace[911378866] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:28093; }","duration":"258.705659ms","start":"2026-07-21T21:48:34.72494Z","end":"2026-07-21T21:48:34.983646Z","steps":["trace[911378866] 'agreement among raft nodes before linearized reading' (duration: 258.603197ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:34.983478Z","caller":"traceutil/trace.go:171","msg":"trace[1983830283] transaction","detail":"{read_only:false; response_revision:28091; number_of_response:1; }","duration":"293.301297ms","start":"2026-07-21T21:48:34.690161Z","end":"2026-07-21T21:48:34.983463Z","steps":["trace[1983830283] 'process raft request' (duration: 293.178174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:34.983663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.793553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:48:34.983736Z","caller":"traceutil/trace.go:171","msg":"trace[1896781809] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:28093; }","duration":"245.893944ms","start":"2026-07-21T21:48:34.737823Z","end":"2026-07-21T21:48:34.983717Z","steps":["trace[1896781809] 'agreement among raft nodes before linearized reading' (duration: 245.771792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:34.985192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:34.564929Z","time spent":"418.66297ms","remote":"127.0.0.1:33808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:48:35.180241Z","caller":"traceutil/trace.go:171","msg":"trace[279270439] transaction","detail":"{read_only:false; response_revision:28095; number_of_response:1; }","duration":"184.407035ms","start":"2026-07-21T21:48:34.995808Z","end":"2026-07-21T21:48:35.180215Z","steps":["trace[279270439] 'process raft request' (duration: 184.208222ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:35.556681Z","caller":"traceutil/trace.go:171","msg":"trace[1657407170] linearizableReadLoop","detail":"{readStateIndex:29381; appliedIndex:29379; }","duration":"501.163989ms","start":"2026-07-21T21:48:35.055494Z","end":"2026-07-21T21:48:35.556658Z","steps":["trace[1657407170] 'read index received' (duration: 124.506537ms)","trace[1657407170] 'applied index is now lower than readState.Index' (duration: 376.656462ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:35.556786Z","caller":"traceutil/trace.go:171","msg":"trace[988409787] transaction","detail":"{read_only:false; response_revision:28096; number_of_response:1; }","duration":"540.862583ms","start":"2026-07-21T21:48:35.015914Z","end":"2026-07-21T21:48:35.556776Z","steps":["trace[988409787] 'process raft request' (duration: 540.599528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:35.556883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:35.015892Z","time spent":"540.918514ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:48:35.55699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.472855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-07-21T21:48:35.557062Z","caller":"traceutil/trace.go:171","msg":"trace[1185419430] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28096; }","duration":"501.604478ms","start":"2026-07-21T21:48:35.055439Z","end":"2026-07-21T21:48:35.557044Z","steps":["trace[1185419430] 'agreement among raft nodes before linearized reading' (duration: 501.359212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:35.557114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:35.055418Z","time spent":"501.68092ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":550,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-07-21T21:48:35.851313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.282328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:48:35.8518Z","caller":"traceutil/trace.go:171","msg":"trace[234830099] transaction","detail":"{read_only:false; response_revision:28100; number_of_response:1; }","duration":"238.466264ms","start":"2026-07-21T21:48:35.613311Z","end":"2026-07-21T21:48:35.851777Z","steps":["trace[234830099] 'process raft request' (duration: 238.409063ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:35.851868Z","caller":"traceutil/trace.go:171","msg":"trace[1057892919] linearizableReadLoop","detail":"{readStateIndex:29383; appliedIndex:29381; }","duration":"295.103961ms","start":"2026-07-21T21:48:35.556743Z","end":"2026-07-21T21:48:35.851847Z","steps":["trace[1057892919] 'read index received' (duration: 93.178172ms)","trace[1057892919] 'applied index is now lower than readState.Index' (duration: 201.923739ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:48:35.852008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.119392ms","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-07-21T21:48:35.85204Z","caller":"traceutil/trace.go:171","msg":"trace[249018826] 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:28100; }","duration":"446.195292ms","start":"2026-07-21T21:48:35.405836Z","end":"2026-07-21T21:48:35.852032Z","steps":["trace[249018826] 'agreement among raft nodes before linearized reading' (duration: 446.102221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:35.852064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:35.405794Z","time spent":"446.263974ms","remote":"127.0.0.1:47656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-07-21T21:48:35.852113Z","caller":"traceutil/trace.go:171","msg":"trace[1862460899] transaction","detail":"{read_only:false; response_revision:28099; number_of_response:1; }","duration":"289.740819ms","start":"2026-07-21T21:48:35.562325Z","end":"2026-07-21T21:48:35.852066Z","steps":["trace[1862460899] 'process raft request' (duration: 289.334052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:35.8522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.461737ms","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-07-21T21:48:35.851831Z","caller":"traceutil/trace.go:171","msg":"trace[1822618152] transaction","detail":"{read_only:false; response_revision:28098; number_of_response:1; }","duration":"376.512588ms","start":"2026-07-21T21:48:35.475288Z","end":"2026-07-21T21:48:35.851801Z","steps":["trace[1822618152] 'process raft request' (duration: 376.162642ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:35.852261Z","caller":"traceutil/trace.go:171","msg":"trace[1811867830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28100; }","duration":"548.574989ms","start":"2026-07-21T21:48:35.303673Z","end":"2026-07-21T21:48:35.852248Z","steps":["trace[1811867830] 'agreement among raft nodes before linearized reading' (duration: 548.405796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:35.852299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:35.303653Z","time spent":"548.6377ms","remote":"127.0.0.1:33990","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-07-21T21:48:35.852342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.212436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"warn","ts":"2026-07-21T21:48:35.852341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.206441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:48:35.852374Z","caller":"traceutil/trace.go:171","msg":"trace[647966276] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28100; }","duration":"637.288678ms","start":"2026-07-21T21:48:35.215079Z","end":"2026-07-21T21:48:35.852367Z","steps":["trace[647966276] 'agreement among raft nodes before linearized reading' (duration: 637.174784ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:35.852388Z","caller":"traceutil/trace.go:171","msg":"trace[298194620] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:28100; }","duration":"722.269323ms","start":"2026-07-21T21:48:35.130104Z","end":"2026-07-21T21:48:35.852374Z","steps":["trace[298194620] 'agreement among raft nodes before linearized reading' (duration: 722.17201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:35.852399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:35.215056Z","time spent":"637.336938ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11520,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-21T21:48:35.852421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:35.130047Z","time spent":"722.364684ms","remote":"127.0.0.1:47698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:48:35.852462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.180217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:48:35.8525Z","caller":"traceutil/trace.go:171","msg":"trace[1635423547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28100; }","duration":"543.251649ms","start":"2026-07-21T21:48:35.30924Z","end":"2026-07-21T21:48:35.852491Z","steps":["trace[1635423547] 'agreement among raft nodes before linearized reading' (duration: 543.205388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:35.852525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:35.309219Z","time spent":"543.300519ms","remote":"127.0.0.1:33672","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-07-21T21:48:35.852426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.444688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:48:35.852623Z","caller":"traceutil/trace.go:171","msg":"trace[623681961] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:28100; }","duration":"507.663953ms","start":"2026-07-21T21:48:35.344947Z","end":"2026-07-21T21:48:35.852611Z","steps":["trace[623681961] 'agreement among raft nodes before linearized reading' (duration: 507.446359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:35.852577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:35.475264Z","time spent":"377.019498ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:48:35.852667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:35.344935Z","time spent":"507.718874ms","remote":"127.0.0.1:35630","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":"info","ts":"2026-07-21T21:48:35.852761Z","caller":"traceutil/trace.go:171","msg":"trace[983527687] transaction","detail":"{read_only:false; response_revision:28097; number_of_response:1; }","duration":"548.091682ms","start":"2026-07-21T21:48:35.304657Z","end":"2026-07-21T21:48:35.852749Z","steps":["trace[983527687] 'process raft request' (duration: 345.244195ms)","trace[983527687] 'compare' (duration: 200.782827ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:48:35.852848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:35.304639Z","time spent":"548.156812ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:48:35.899871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.299141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:48:35.89995Z","caller":"traceutil/trace.go:171","msg":"trace[250424146] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:28101; }","duration":"338.435225ms","start":"2026-07-21T21:48:35.561495Z","end":"2026-07-21T21:48:35.899931Z","steps":["trace[250424146] 'agreement among raft nodes before linearized reading' (duration: 338.285821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:35.900073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:35.561469Z","time spent":"338.579848ms","remote":"127.0.0.1:39490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:48:35.900689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.68244ms","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-07-21T21:48:35.900756Z","caller":"traceutil/trace.go:171","msg":"trace[1229451034] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:28101; }","duration":"104.793563ms","start":"2026-07-21T21:48:35.795949Z","end":"2026-07-21T21:48:35.900743Z","steps":["trace[1229451034] 'agreement among raft nodes before linearized reading' (duration: 104.64873ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:36.100796Z","caller":"traceutil/trace.go:171","msg":"trace[1042910098] transaction","detail":"{read_only:false; response_revision:28106; number_of_response:1; }","duration":"158.446042ms","start":"2026-07-21T21:48:35.942323Z","end":"2026-07-21T21:48:36.100769Z","steps":["trace[1042910098] 'process raft request' (duration: 158.197557ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:36.229707Z","caller":"traceutil/trace.go:171","msg":"trace[1916019980] transaction","detail":"{read_only:false; response_revision:28107; number_of_response:1; }","duration":"124.123401ms","start":"2026-07-21T21:48:36.105558Z","end":"2026-07-21T21:48:36.229681Z","steps":["trace[1916019980] 'process raft request' (duration: 110.89682ms)","trace[1916019980] 'compare' (duration: 13.126929ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:37.127345Z","caller":"traceutil/trace.go:171","msg":"trace[528313640] transaction","detail":"{read_only:false; response_revision:28108; number_of_response:1; }","duration":"138.038084ms","start":"2026-07-21T21:48:36.989281Z","end":"2026-07-21T21:48:37.127319Z","steps":["trace[528313640] 'process raft request' (duration: 137.873341ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:37.139396Z","caller":"traceutil/trace.go:171","msg":"trace[632702343] transaction","detail":"{read_only:false; response_revision:28109; number_of_response:1; }","duration":"150.054602ms","start":"2026-07-21T21:48:36.989307Z","end":"2026-07-21T21:48:37.139361Z","steps":["trace[632702343] 'process raft request' (duration: 149.9542ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:37.139425Z","caller":"traceutil/trace.go:171","msg":"trace[210135572] transaction","detail":"{read_only:false; response_revision:28110; number_of_response:1; }","duration":"149.989001ms","start":"2026-07-21T21:48:36.989414Z","end":"2026-07-21T21:48:37.139403Z","steps":["trace[210135572] 'process raft request' (duration: 149.901489ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:37.139375Z","caller":"traceutil/trace.go:171","msg":"trace[200079710] linearizableReadLoop","detail":"{readStateIndex:29395; appliedIndex:29392; }","duration":"123.141721ms","start":"2026-07-21T21:48:37.016222Z","end":"2026-07-21T21:48:37.139363Z","steps":["trace[200079710] 'read index received' (duration: 110.932859ms)","trace[200079710] 'applied index is now lower than readState.Index' (duration: 12.207712ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:48:37.139993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.756482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-21T21:48:37.140022Z","caller":"traceutil/trace.go:171","msg":"trace[305041069] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28110; }","duration":"123.831414ms","start":"2026-07-21T21:48:37.016182Z","end":"2026-07-21T21:48:37.140014Z","steps":["trace[305041069] 'agreement among raft nodes before linearized reading' (duration: 123.703852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:37.475195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.007116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:48:37.475263Z","caller":"traceutil/trace.go:171","msg":"trace[1535528492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28111; }","duration":"167.123778ms","start":"2026-07-21T21:48:37.308124Z","end":"2026-07-21T21:48:37.475248Z","steps":["trace[1535528492] 'range keys from in-memory index tree' (duration: 166.888234ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:37.793156Z","caller":"traceutil/trace.go:171","msg":"trace[1858441713] transaction","detail":"{read_only:false; response_revision:28112; number_of_response:1; }","duration":"224.501049ms","start":"2026-07-21T21:48:37.568629Z","end":"2026-07-21T21:48:37.79313Z","steps":["trace[1858441713] 'process raft request' (duration: 224.315846ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:38.078507Z","caller":"traceutil/trace.go:171","msg":"trace[1860002752] transaction","detail":"{read_only:false; response_revision:28115; number_of_response:1; }","duration":"219.85247ms","start":"2026-07-21T21:48:37.858625Z","end":"2026-07-21T21:48:38.078478Z","steps":["trace[1860002752] 'process raft request' (duration: 219.794449ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:38.07857Z","caller":"traceutil/trace.go:171","msg":"trace[1313966664] linearizableReadLoop","detail":"{readStateIndex:29398; appliedIndex:29397; }","duration":"220.549824ms","start":"2026-07-21T21:48:37.857991Z","end":"2026-07-21T21:48:38.07854Z","steps":["trace[1313966664] 'read index received' (duration: 122.416147ms)","trace[1313966664] 'applied index is now lower than readState.Index' (duration: 98.130587ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:38.078722Z","caller":"traceutil/trace.go:171","msg":"trace[812021188] transaction","detail":"{read_only:false; response_revision:28113; number_of_response:1; }","duration":"276.344045ms","start":"2026-07-21T21:48:37.802363Z","end":"2026-07-21T21:48:38.078707Z","steps":["trace[812021188] 'process raft request' (duration: 178.104516ms)","trace[812021188] 'compare' (duration: 97.500174ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:38.078785Z","caller":"traceutil/trace.go:171","msg":"trace[93429580] transaction","detail":"{read_only:false; response_revision:28114; number_of_response:1; }","duration":"220.773688ms","start":"2026-07-21T21:48:37.857993Z","end":"2026-07-21T21:48:38.078766Z","steps":["trace[93429580] 'process raft request' (duration: 220.301578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:38.0788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.038485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:48:38.078871Z","caller":"traceutil/trace.go:171","msg":"trace[332384632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28115; }","duration":"168.148627ms","start":"2026-07-21T21:48:37.910704Z","end":"2026-07-21T21:48:38.078853Z","steps":["trace[332384632] 'agreement among raft nodes before linearized reading' (duration: 168.036234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:38.078905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.799797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-07-21T21:48:38.078949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.961161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-21T21:48:38.078962Z","caller":"traceutil/trace.go:171","msg":"trace[1686363952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28115; }","duration":"160.906459ms","start":"2026-07-21T21:48:37.918042Z","end":"2026-07-21T21:48:38.078948Z","steps":["trace[1686363952] 'agreement among raft nodes before linearized reading' (duration: 160.713386ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:38.07898Z","caller":"traceutil/trace.go:171","msg":"trace[1900216265] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28115; }","duration":"221.042833ms","start":"2026-07-21T21:48:37.857929Z","end":"2026-07-21T21:48:38.078972Z","steps":["trace[1900216265] 'agreement among raft nodes before linearized reading' (duration: 220.921401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:38.079026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.064425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:48:38.079067Z","caller":"traceutil/trace.go:171","msg":"trace[1612380543] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:28115; }","duration":"128.149887ms","start":"2026-07-21T21:48:37.950907Z","end":"2026-07-21T21:48:38.079057Z","steps":["trace[1612380543] 'agreement among raft nodes before linearized reading' (duration: 128.041005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:38.07911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.228184ms","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-07-21T21:48:38.079133Z","caller":"traceutil/trace.go:171","msg":"trace[117374952] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28115; }","duration":"162.283176ms","start":"2026-07-21T21:48:37.916844Z","end":"2026-07-21T21:48:38.079127Z","steps":["trace[117374952] 'agreement among raft nodes before linearized reading' (duration: 162.219624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:38.07915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.747856ms","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-07-21T21:48:38.079183Z","caller":"traceutil/trace.go:171","msg":"trace[1579445679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28115; }","duration":"161.818387ms","start":"2026-07-21T21:48:37.917355Z","end":"2026-07-21T21:48:38.079173Z","steps":["trace[1579445679] 'agreement among raft nodes before linearized reading' (duration: 161.703775ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:38.450733Z","caller":"traceutil/trace.go:171","msg":"trace[25769590] transaction","detail":"{read_only:false; response_revision:28117; number_of_response:1; }","duration":"368.18436ms","start":"2026-07-21T21:48:38.082522Z","end":"2026-07-21T21:48:38.450706Z","steps":["trace[25769590] 'process raft request' (duration: 270.959692ms)","trace[25769590] 'compare' (duration: 97.043555ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:38.451105Z","caller":"traceutil/trace.go:171","msg":"trace[1543725805] transaction","detail":"{read_only:false; response_revision:28118; number_of_response:1; }","duration":"367.062009ms","start":"2026-07-21T21:48:38.083937Z","end":"2026-07-21T21:48:38.450999Z","steps":["trace[1543725805] 'process raft request' (duration: 366.722632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:38.451257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:38.083927Z","time spent":"367.240443ms","remote":"127.0.0.1:33990","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-07-21T21:48:38.451262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.531019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-07-21T21:48:38.451247Z","caller":"traceutil/trace.go:171","msg":"trace[373500033] transaction","detail":"{read_only:false; response_revision:28119; number_of_response:1; }","duration":"366.433567ms","start":"2026-07-21T21:48:38.084778Z","end":"2026-07-21T21:48:38.451211Z","steps":["trace[373500033] 'process raft request' (duration: 366.08664ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:38.451325Z","caller":"traceutil/trace.go:171","msg":"trace[241643758] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28119; }","duration":"346.58398ms","start":"2026-07-21T21:48:38.104713Z","end":"2026-07-21T21:48:38.451297Z","steps":["trace[241643758] 'agreement among raft nodes before linearized reading' (duration: 346.432457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:38.451359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:38.104648Z","time spent":"346.702692ms","remote":"127.0.0.1:33808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":685,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-07-21T21:48:38.45109Z","caller":"traceutil/trace.go:171","msg":"trace[1296481421] linearizableReadLoop","detail":"{readStateIndex:29404; appliedIndex:29401; }","duration":"346.350986ms","start":"2026-07-21T21:48:38.104719Z","end":"2026-07-21T21:48:38.45107Z","steps":["trace[1296481421] 'read index received' (duration: 30.303796ms)","trace[1296481421] 'applied index is now lower than readState.Index' (duration: 316.04543ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:48:38.45143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:38.08477Z","time spent":"366.561119ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:48:38.451591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.427204ms","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-07-21T21:48:38.451628Z","caller":"traceutil/trace.go:171","msg":"trace[234548599] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28119; }","duration":"217.459514ms","start":"2026-07-21T21:48:38.234154Z","end":"2026-07-21T21:48:38.451614Z","steps":["trace[234548599] 'agreement among raft nodes before linearized reading' (duration: 217.358473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:38.450931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:38.082514Z","time spent":"368.291112ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:48:38.45169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.547591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-21T21:48:38.451858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.472131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-21T21:48:38.451892Z","caller":"traceutil/trace.go:171","msg":"trace[1370455720] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:28119; }","duration":"140.552162ms","start":"2026-07-21T21:48:38.311327Z","end":"2026-07-21T21:48:38.45188Z","steps":["trace[1370455720] 'agreement among raft nodes before linearized reading' (duration: 140.44457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:38.451876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.306627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:48:38.451912Z","caller":"traceutil/trace.go:171","msg":"trace[1319297263] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:28119; }","duration":"122.771004ms","start":"2026-07-21T21:48:38.329106Z","end":"2026-07-21T21:48:38.451877Z","steps":["trace[1319297263] 'agreement among raft nodes before linearized reading' (duration: 122.54738ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:38.45195Z","caller":"traceutil/trace.go:171","msg":"trace[1167516402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28119; }","duration":"141.413038ms","start":"2026-07-21T21:48:38.310517Z","end":"2026-07-21T21:48:38.45193Z","steps":["trace[1167516402] 'agreement among raft nodes before linearized reading' (duration: 141.253015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:38.683329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.145793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:48:38.683438Z","caller":"traceutil/trace.go:171","msg":"trace[1964651142] transaction","detail":"{read_only:false; response_revision:28121; number_of_response:1; }","duration":"223.896027ms","start":"2026-07-21T21:48:38.459521Z","end":"2026-07-21T21:48:38.683417Z","steps":["trace[1964651142] 'process raft request' (duration: 122.56745ms)","trace[1964651142] 'compare' (duration: 100.799356ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:38.964072Z","caller":"traceutil/trace.go:171","msg":"trace[1282381404] transaction","detail":"{read_only:false; response_revision:28122; number_of_response:1; }","duration":"273.913318ms","start":"2026-07-21T21:48:38.690134Z","end":"2026-07-21T21:48:38.964048Z","steps":["trace[1282381404] 'process raft request' (duration: 273.578262ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:39.259806Z","caller":"traceutil/trace.go:171","msg":"trace[337520841] transaction","detail":"{read_only:false; response_revision:28123; number_of_response:1; }","duration":"127.423252ms","start":"2026-07-21T21:48:39.132338Z","end":"2026-07-21T21:48:39.259762Z","steps":["trace[337520841] 'process raft request' (duration: 118.538334ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:39.290754Z","caller":"traceutil/trace.go:171","msg":"trace[1741214279] transaction","detail":"{read_only:false; response_revision:28124; number_of_response:1; }","duration":"146.364602ms","start":"2026-07-21T21:48:39.144371Z","end":"2026-07-21T21:48:39.290735Z","steps":["trace[1741214279] 'process raft request' (duration: 146.2264ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:39.290938Z","caller":"traceutil/trace.go:171","msg":"trace[2428766] transaction","detail":"{read_only:false; response_revision:28125; number_of_response:1; }","duration":"146.166578ms","start":"2026-07-21T21:48:39.144739Z","end":"2026-07-21T21:48:39.290905Z","steps":["trace[2428766] 'process raft request' (duration: 145.959634ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:39.290977Z","caller":"traceutil/trace.go:171","msg":"trace[1514313824] linearizableReadLoop","detail":"{readStateIndex:29410; appliedIndex:29407; }","duration":"109.835558ms","start":"2026-07-21T21:48:39.181118Z","end":"2026-07-21T21:48:39.290954Z","steps":["trace[1514313824] 'read index received' (duration: 69.779307ms)","trace[1514313824] 'applied index is now lower than readState.Index' (duration: 40.053561ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:48:39.291109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.981661ms","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-07-21T21:48:39.291145Z","caller":"traceutil/trace.go:171","msg":"trace[1213293101] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28125; }","duration":"110.078133ms","start":"2026-07-21T21:48:39.181058Z","end":"2026-07-21T21:48:39.291136Z","steps":["trace[1213293101] 'agreement among raft nodes before linearized reading' (duration: 109.94729ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:39.522557Z","caller":"traceutil/trace.go:171","msg":"trace[939111486] transaction","detail":"{read_only:false; response_revision:28126; number_of_response:1; }","duration":"266.349054ms","start":"2026-07-21T21:48:39.256166Z","end":"2026-07-21T21:48:39.522515Z","steps":["trace[939111486] 'process raft request' (duration: 166.783221ms)","trace[939111486] 'compare' (duration: 98.90874ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:39.52459Z","caller":"traceutil/trace.go:171","msg":"trace[245104669] linearizableReadLoop","detail":"{readStateIndex:29412; appliedIndex:29410; }","duration":"233.290405ms","start":"2026-07-21T21:48:39.291047Z","end":"2026-07-21T21:48:39.524337Z","steps":["trace[245104669] 'read index received' (duration: 131.881378ms)","trace[245104669] 'applied index is now lower than readState.Index' (duration: 101.407557ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:48:39.52496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.861829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-21T21:48:39.525036Z","caller":"traceutil/trace.go:171","msg":"trace[688378104] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:28126; }","duration":"291.995822ms","start":"2026-07-21T21:48:39.233022Z","end":"2026-07-21T21:48:39.525018Z","steps":["trace[688378104] 'agreement among raft nodes before linearized reading' (duration: 291.707576ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:39.560341Z","caller":"traceutil/trace.go:171","msg":"trace[1629441858] transaction","detail":"{read_only:false; response_revision:28127; number_of_response:1; }","duration":"264.916307ms","start":"2026-07-21T21:48:39.295408Z","end":"2026-07-21T21:48:39.560324Z","steps":["trace[1629441858] 'process raft request' (duration: 264.729704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:39.57316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.553257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:48:39.573214Z","caller":"traceutil/trace.go:171","msg":"trace[942291953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28128; }","duration":"266.652989ms","start":"2026-07-21T21:48:39.306546Z","end":"2026-07-21T21:48:39.573199Z","steps":["trace[942291953] 'agreement among raft nodes before linearized reading' (duration: 266.528267ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:45.297688Z","caller":"traceutil/trace.go:171","msg":"trace[719450693] linearizableReadLoop","detail":"{readStateIndex:29464; appliedIndex:29463; }","duration":"214.811225ms","start":"2026-07-21T21:48:45.08285Z","end":"2026-07-21T21:48:45.297662Z","steps":["trace[719450693] 'read index received' (duration: 214.653991ms)","trace[719450693] 'applied index is now lower than readState.Index' (duration: 155.254µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:45.298006Z","caller":"traceutil/trace.go:171","msg":"trace[1792614244] transaction","detail":"{read_only:false; response_revision:28176; number_of_response:1; }","duration":"274.338146ms","start":"2026-07-21T21:48:45.023655Z","end":"2026-07-21T21:48:45.297994Z","steps":["trace[1792614244] 'process raft request' (duration: 273.817776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:45.298389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.509807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-21T21:48:45.298475Z","caller":"traceutil/trace.go:171","msg":"trace[110704346] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:28176; }","duration":"215.711362ms","start":"2026-07-21T21:48:45.082745Z","end":"2026-07-21T21:48:45.298456Z","steps":["trace[110704346] 'agreement among raft nodes before linearized reading' (duration: 215.443297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:45.301183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.762686ms","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-07-21T21:48:45.301299Z","caller":"traceutil/trace.go:171","msg":"trace[1629650019] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:28177; }","duration":"191.878218ms","start":"2026-07-21T21:48:45.109363Z","end":"2026-07-21T21:48:45.301241Z","steps":["trace[1629650019] 'agreement among raft nodes before linearized reading' (duration: 191.730295ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:45.721055Z","caller":"traceutil/trace.go:171","msg":"trace[1802229778] transaction","detail":"{read_only:false; response_revision:28178; number_of_response:1; }","duration":"414.955789ms","start":"2026-07-21T21:48:45.306055Z","end":"2026-07-21T21:48:45.721011Z","steps":["trace[1802229778] 'process raft request' (duration: 315.924156ms)","trace[1802229778] 'compare' (duration: 98.660566ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:48:45.721281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:45.306029Z","time spent":"415.168233ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":551,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:48:45.721302Z","caller":"traceutil/trace.go:171","msg":"trace[2083278070] linearizableReadLoop","detail":"{readStateIndex:29468; appliedIndex:29465; }","duration":"139.514182ms","start":"2026-07-21T21:48:45.58175Z","end":"2026-07-21T21:48:45.721264Z","steps":["trace[2083278070] 'read index received' (duration: 40.244935ms)","trace[2083278070] 'applied index is now lower than readState.Index' (duration: 99.268397ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:48:45.721472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.715027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-21T21:48:45.721482Z","caller":"traceutil/trace.go:171","msg":"trace[129143938] transaction","detail":"{read_only:false; response_revision:28179; number_of_response:1; }","duration":"413.495961ms","start":"2026-07-21T21:48:45.307958Z","end":"2026-07-21T21:48:45.721454Z","steps":["trace[129143938] 'process raft request' (duration: 412.974101ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:45.721528Z","caller":"traceutil/trace.go:171","msg":"trace[1012417151] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28180; }","duration":"139.823918ms","start":"2026-07-21T21:48:45.581693Z","end":"2026-07-21T21:48:45.721517Z","steps":["trace[1012417151] 'agreement among raft nodes before linearized reading' (duration: 139.656745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:45.721613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:45.307939Z","time spent":"413.595623ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:48:45.721652Z","caller":"traceutil/trace.go:171","msg":"trace[1455556023] transaction","detail":"{read_only:false; response_revision:28180; number_of_response:1; }","duration":"408.545037ms","start":"2026-07-21T21:48:45.313097Z","end":"2026-07-21T21:48:45.721642Z","steps":["trace[1455556023] 'process raft request' (duration: 408.074669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:45.72173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:45.313079Z","time spent":"408.594668ms","remote":"127.0.0.1:33990","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-07-21T21:48:45.924723Z","caller":"traceutil/trace.go:171","msg":"trace[1759106953] transaction","detail":"{read_only:false; response_revision:28181; number_of_response:1; }","duration":"198.720018ms","start":"2026-07-21T21:48:45.725981Z","end":"2026-07-21T21:48:45.924701Z","steps":["trace[1759106953] 'process raft request' (duration: 197.835722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:46.235174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.111238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:48:46.235277Z","caller":"traceutil/trace.go:171","msg":"trace[1603754805] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:28182; }","duration":"186.253021ms","start":"2026-07-21T21:48:46.048987Z","end":"2026-07-21T21:48:46.23524Z","steps":["trace[1603754805] 'count revisions from in-memory index tree' (duration: 186.004477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:46.235388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.000509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-07-21T21:48:46.235439Z","caller":"traceutil/trace.go:171","msg":"trace[1776375348] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28182; }","duration":"153.075671ms","start":"2026-07-21T21:48:46.08235Z","end":"2026-07-21T21:48:46.235426Z","steps":["trace[1776375348] 'range keys from in-memory index tree' (duration: 152.771254ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:46.589857Z","caller":"traceutil/trace.go:171","msg":"trace[1021165364] transaction","detail":"{read_only:false; response_revision:28183; number_of_response:1; }","duration":"487.372826ms","start":"2026-07-21T21:48:46.102412Z","end":"2026-07-21T21:48:46.589785Z","steps":["trace[1021165364] 'process raft request' (duration: 487.154812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:46.590055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:46.102386Z","time spent":"487.562071ms","remote":"127.0.0.1:33990","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-07-21T21:48:46.616739Z","caller":"traceutil/trace.go:171","msg":"trace[1149906662] linearizableReadLoop","detail":"{readStateIndex:29472; appliedIndex:29470; }","duration":"473.045783ms","start":"2026-07-21T21:48:46.143669Z","end":"2026-07-21T21:48:46.616714Z","steps":["trace[1149906662] 'read index received' (duration: 446.028429ms)","trace[1149906662] 'applied index is now lower than readState.Index' (duration: 27.016204ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:46.616848Z","caller":"traceutil/trace.go:171","msg":"trace[301977725] transaction","detail":"{read_only:false; response_revision:28184; number_of_response:1; }","duration":"511.70246ms","start":"2026-07-21T21:48:46.105134Z","end":"2026-07-21T21:48:46.616837Z","steps":["trace[301977725] 'process raft request' (duration: 511.417985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:46.617109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:46.105116Z","time spent":"511.75711ms","remote":"127.0.0.1:33990","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-07-21T21:48:46.617115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.025228ms","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-07-21T21:48:46.617182Z","caller":"traceutil/trace.go:171","msg":"trace[738969042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28184; }","duration":"134.11347ms","start":"2026-07-21T21:48:46.483051Z","end":"2026-07-21T21:48:46.617165Z","steps":["trace[738969042] 'agreement among raft nodes before linearized reading' (duration: 133.939617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:46.61713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.910383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-07-21T21:48:46.617355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.695446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-07-21T21:48:46.617925Z","caller":"traceutil/trace.go:171","msg":"trace[500722061] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28184; }","duration":"474.272056ms","start":"2026-07-21T21:48:46.143609Z","end":"2026-07-21T21:48:46.617881Z","steps":["trace[500722061] 'agreement among raft nodes before linearized reading' (duration: 473.671295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:46.61815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:46.143587Z","time spent":"474.482611ms","remote":"127.0.0.1:33808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":670,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-21T21:48:46.617536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.676418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:48:46.618371Z","caller":"traceutil/trace.go:171","msg":"trace[820099110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28184; }","duration":"309.628466ms","start":"2026-07-21T21:48:46.308727Z","end":"2026-07-21T21:48:46.618356Z","steps":["trace[820099110] 'agreement among raft nodes before linearized reading' (duration: 308.601687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:46.618426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:46.308708Z","time spent":"309.702657ms","remote":"127.0.0.1:33672","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-07-21T21:48:46.61757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.349845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-07-21T21:48:46.61854Z","caller":"traceutil/trace.go:171","msg":"trace[801071592] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28184; }","duration":"134.334074ms","start":"2026-07-21T21:48:46.484181Z","end":"2026-07-21T21:48:46.618515Z","steps":["trace[801071592] 'agreement among raft nodes before linearized reading' (duration: 133.196003ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:46.617553Z","caller":"traceutil/trace.go:171","msg":"trace[1810493476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28184; }","duration":"441.276869ms","start":"2026-07-21T21:48:46.176199Z","end":"2026-07-21T21:48:46.617476Z","steps":["trace[1810493476] 'agreement among raft nodes before linearized reading' (duration: 440.74508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:46.618671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:46.176166Z","time spent":"442.486833ms","remote":"127.0.0.1:33990","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-07-21T21:48:46.617629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.960617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-07-21T21:48:46.618779Z","caller":"traceutil/trace.go:171","msg":"trace[532341593] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28184; }","duration":"378.13446ms","start":"2026-07-21T21:48:46.240611Z","end":"2026-07-21T21:48:46.618745Z","steps":["trace[532341593] 'agreement among raft nodes before linearized reading' (duration: 376.882296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:46.618842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:48:46.240589Z","time spent":"378.236751ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11520,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-21T21:48:46.617668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.63421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-21T21:48:46.618893Z","caller":"traceutil/trace.go:171","msg":"trace[1069251237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28184; }","duration":"135.896264ms","start":"2026-07-21T21:48:46.482982Z","end":"2026-07-21T21:48:46.618878Z","steps":["trace[1069251237] 'agreement among raft nodes before linearized reading' (duration: 134.579349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:50.496772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.017483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:48:50.496917Z","caller":"traceutil/trace.go:171","msg":"trace[817727205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28273; }","duration":"190.241137ms","start":"2026-07-21T21:48:50.306643Z","end":"2026-07-21T21:48:50.496885Z","steps":["trace[817727205] 'range keys from in-memory index tree' (duration: 189.927022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:50.893317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.705958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:48:50.894358Z","caller":"traceutil/trace.go:171","msg":"trace[1367071404] transaction","detail":"{read_only:false; response_revision:28275; number_of_response:1; }","duration":"265.314135ms","start":"2026-07-21T21:48:50.62902Z","end":"2026-07-21T21:48:50.894334Z","steps":["trace[1367071404] 'process raft request' (duration: 156.664199ms)","trace[1367071404] 'compare' (duration: 106.373292ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:50.894607Z","caller":"traceutil/trace.go:171","msg":"trace[1117236811] linearizableReadLoop","detail":"{readStateIndex:29565; appliedIndex:29563; }","duration":"255.734962ms","start":"2026-07-21T21:48:50.63883Z","end":"2026-07-21T21:48:50.894565Z","steps":["trace[1117236811] 'read index received' (duration: 814.026µs)","trace[1117236811] 'applied index is now lower than readState.Index' (duration: 254.911246ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:48:50.894982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.158531ms","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-07-21T21:48:50.895015Z","caller":"traceutil/trace.go:171","msg":"trace[1962793471] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28276; }","duration":"256.243941ms","start":"2026-07-21T21:48:50.638758Z","end":"2026-07-21T21:48:50.895002Z","steps":["trace[1962793471] 'agreement among raft nodes before linearized reading' (duration: 256.077098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:50.895154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.219855ms","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-07-21T21:48:50.895251Z","caller":"traceutil/trace.go:171","msg":"trace[1451459257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28276; }","duration":"253.340816ms","start":"2026-07-21T21:48:50.641883Z","end":"2026-07-21T21:48:50.895223Z","steps":["trace[1451459257] 'agreement among raft nodes before linearized reading' (duration: 253.088212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:50.895518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.400206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:48:50.895552Z","caller":"traceutil/trace.go:171","msg":"trace[1868724585] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:28276; }","duration":"134.476567ms","start":"2026-07-21T21:48:50.761068Z","end":"2026-07-21T21:48:50.895545Z","steps":["trace[1868724585] 'agreement among raft nodes before linearized reading' (duration: 134.378185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:50.895672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.743537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-21T21:48:50.89575Z","caller":"traceutil/trace.go:171","msg":"trace[1932853599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28276; }","duration":"250.831189ms","start":"2026-07-21T21:48:50.644899Z","end":"2026-07-21T21:48:50.895731Z","steps":["trace[1932853599] 'agreement among raft nodes before linearized reading' (duration: 250.573304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:50.895277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.868316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-07-21T21:48:50.895937Z","caller":"traceutil/trace.go:171","msg":"trace[393259923] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28276; }","duration":"234.544109ms","start":"2026-07-21T21:48:50.661382Z","end":"2026-07-21T21:48:50.895926Z","steps":["trace[393259923] 'agreement among raft nodes before linearized reading' (duration: 233.847306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:50.896004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.789346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-21T21:48:50.896069Z","caller":"traceutil/trace.go:171","msg":"trace[539737655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28276; }","duration":"252.880249ms","start":"2026-07-21T21:48:50.643172Z","end":"2026-07-21T21:48:50.896052Z","steps":["trace[539737655] 'agreement among raft nodes before linearized reading' (duration: 252.722146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:50.896084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.94931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-07-21T21:48:50.896199Z","caller":"traceutil/trace.go:171","msg":"trace[1034189610] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28276; }","duration":"253.088872ms","start":"2026-07-21T21:48:50.643098Z","end":"2026-07-21T21:48:50.896187Z","steps":["trace[1034189610] 'agreement among raft nodes before linearized reading' (duration: 252.939209ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:51.069116Z","caller":"traceutil/trace.go:171","msg":"trace[769136652] linearizableReadLoop","detail":"{readStateIndex:29567; appliedIndex:29566; }","duration":"161.559742ms","start":"2026-07-21T21:48:50.90753Z","end":"2026-07-21T21:48:51.069089Z","steps":["trace[769136652] 'read index received' (duration: 161.420678ms)","trace[769136652] 'applied index is now lower than readState.Index' (duration: 137.934µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:51.069193Z","caller":"traceutil/trace.go:171","msg":"trace[1074790168] transaction","detail":"{read_only:false; response_revision:28277; number_of_response:1; }","duration":"163.474268ms","start":"2026-07-21T21:48:50.905689Z","end":"2026-07-21T21:48:51.069163Z","steps":["trace[1074790168] 'process raft request' (duration: 163.235693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:51.069318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.763247ms","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-07-21T21:48:51.069363Z","caller":"traceutil/trace.go:171","msg":"trace[746957844] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28277; }","duration":"161.844358ms","start":"2026-07-21T21:48:50.907505Z","end":"2026-07-21T21:48:51.069349Z","steps":["trace[746957844] 'agreement among raft nodes before linearized reading' (duration: 161.680585ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:51.188969Z","caller":"traceutil/trace.go:171","msg":"trace[1369590398] transaction","detail":"{read_only:false; response_revision:28278; number_of_response:1; }","duration":"281.342339ms","start":"2026-07-21T21:48:50.907586Z","end":"2026-07-21T21:48:51.188928Z","steps":["trace[1369590398] 'process raft request' (duration: 280.972892ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:51.188966Z","caller":"traceutil/trace.go:171","msg":"trace[963844934] transaction","detail":"{read_only:false; response_revision:28279; number_of_response:1; }","duration":"281.298859ms","start":"2026-07-21T21:48:50.90763Z","end":"2026-07-21T21:48:51.188929Z","steps":["trace[963844934] 'process raft request' (duration: 281.153156ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:51.189265Z","caller":"traceutil/trace.go:171","msg":"trace[1675780243] transaction","detail":"{read_only:false; response_revision:28280; number_of_response:1; }","duration":"281.503562ms","start":"2026-07-21T21:48:50.907695Z","end":"2026-07-21T21:48:51.189198Z","steps":["trace[1675780243] 'process raft request' (duration: 281.154795ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:51.189545Z","caller":"traceutil/trace.go:171","msg":"trace[1796842143] transaction","detail":"{read_only:false; response_revision:28281; number_of_response:1; }","duration":"281.786079ms","start":"2026-07-21T21:48:50.907744Z","end":"2026-07-21T21:48:51.18953Z","steps":["trace[1796842143] 'process raft request' (duration: 281.35319ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:51.18976Z","caller":"traceutil/trace.go:171","msg":"trace[1501305718] transaction","detail":"{read_only:false; response_revision:28282; number_of_response:1; }","duration":"281.747618ms","start":"2026-07-21T21:48:50.907949Z","end":"2026-07-21T21:48:51.189697Z","steps":["trace[1501305718] 'process raft request' (duration: 281.427571ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:51.189857Z","caller":"traceutil/trace.go:171","msg":"trace[187740919] transaction","detail":"{read_only:false; response_revision:28283; number_of_response:1; }","duration":"259.339021ms","start":"2026-07-21T21:48:50.930505Z","end":"2026-07-21T21:48:51.189844Z","steps":["trace[187740919] 'process raft request' (duration: 259.160957ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:51.195266Z","caller":"traceutil/trace.go:171","msg":"trace[1785791146] transaction","detail":"{read_only:false; response_revision:28284; number_of_response:1; }","duration":"119.872069ms","start":"2026-07-21T21:48:51.075371Z","end":"2026-07-21T21:48:51.195243Z","steps":["trace[1785791146] 'process raft request' (duration: 119.725356ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:53.309972Z","caller":"traceutil/trace.go:171","msg":"trace[153721185] transaction","detail":"{read_only:false; response_revision:28300; number_of_response:1; }","duration":"112.661412ms","start":"2026-07-21T21:48:53.197278Z","end":"2026-07-21T21:48:53.30994Z","steps":["trace[153721185] 'process raft request' (duration: 44.365123ms)","trace[153721185] 'compare' (duration: 68.116885ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:53.362835Z","caller":"traceutil/trace.go:171","msg":"trace[933071335] transaction","detail":"{read_only:false; response_revision:28301; number_of_response:1; }","duration":"164.224323ms","start":"2026-07-21T21:48:53.198568Z","end":"2026-07-21T21:48:53.362793Z","steps":["trace[933071335] 'process raft request' (duration: 163.870997ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:53.363034Z","caller":"traceutil/trace.go:171","msg":"trace[1799428874] transaction","detail":"{read_only:false; response_revision:28302; number_of_response:1; }","duration":"164.349474ms","start":"2026-07-21T21:48:53.198661Z","end":"2026-07-21T21:48:53.36301Z","steps":["trace[1799428874] 'process raft request' (duration: 164.011638ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:53.363073Z","caller":"traceutil/trace.go:171","msg":"trace[1733236574] linearizableReadLoop","detail":"{readStateIndex:29594; appliedIndex:29590; }","duration":"154.197752ms","start":"2026-07-21T21:48:53.208856Z","end":"2026-07-21T21:48:53.363054Z","steps":["trace[1733236574] 'read index received' (duration: 32.797023ms)","trace[1733236574] 'applied index is now lower than readState.Index' (duration: 121.399369ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:53.363408Z","caller":"traceutil/trace.go:171","msg":"trace[1157223581] transaction","detail":"{read_only:false; response_revision:28303; number_of_response:1; }","duration":"163.731663ms","start":"2026-07-21T21:48:53.199651Z","end":"2026-07-21T21:48:53.363383Z","steps":["trace[1157223581] 'process raft request' (duration: 163.267084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:53.363497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.624759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-07-21T21:48:53.363538Z","caller":"traceutil/trace.go:171","msg":"trace[1977493067] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28303; }","duration":"154.707ms","start":"2026-07-21T21:48:53.20882Z","end":"2026-07-21T21:48:53.363527Z","steps":["trace[1977493067] 'agreement among raft nodes before linearized reading' (duration: 154.559678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:48:53.365876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.852782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1b0f1da2-f7d3-40bb-b527-bc516d6600af\" ","response":"range_response_count:1 size:3950"} {"level":"info","ts":"2026-07-21T21:48:53.366054Z","caller":"traceutil/trace.go:171","msg":"trace[386451691] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1b0f1da2-f7d3-40bb-b527-bc516d6600af; range_end:; response_count:1; response_revision:28304; }","duration":"148.085855ms","start":"2026-07-21T21:48:53.217943Z","end":"2026-07-21T21:48:53.366029Z","steps":["trace[386451691] 'agreement among raft nodes before linearized reading' (duration: 147.696008ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:56.896116Z","caller":"traceutil/trace.go:171","msg":"trace[688509146] transaction","detail":"{read_only:false; response_revision:28348; number_of_response:1; }","duration":"240.036433ms","start":"2026-07-21T21:48:56.656041Z","end":"2026-07-21T21:48:56.896078Z","steps":["trace[688509146] 'process raft request' (duration: 148.148257ms)","trace[688509146] 'compare' (duration: 91.580341ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:57.055287Z","caller":"traceutil/trace.go:171","msg":"trace[504420654] transaction","detail":"{read_only:false; response_revision:28349; number_of_response:1; }","duration":"132.50171ms","start":"2026-07-21T21:48:56.922748Z","end":"2026-07-21T21:48:57.05525Z","steps":["trace[504420654] 'process raft request' (duration: 107.544735ms)","trace[504420654] 'compare' (duration: 24.809692ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:48:57.057846Z","caller":"traceutil/trace.go:171","msg":"trace[1761660355] transaction","detail":"{read_only:false; response_revision:28350; number_of_response:1; }","duration":"108.205377ms","start":"2026-07-21T21:48:56.949586Z","end":"2026-07-21T21:48:57.057791Z","steps":["trace[1761660355] 'process raft request' (duration: 107.609545ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:17.552989Z","caller":"traceutil/trace.go:171","msg":"trace[849852744] transaction","detail":"{read_only:false; response_revision:28555; number_of_response:1; }","duration":"104.691601ms","start":"2026-07-21T21:49:17.448253Z","end":"2026-07-21T21:49:17.552945Z","steps":["trace[849852744] 'process raft request' (duration: 43.343764ms)","trace[849852744] 'compare' (duration: 61.206714ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:49:17.667372Z","caller":"traceutil/trace.go:171","msg":"trace[1999529382] transaction","detail":"{read_only:false; response_revision:28556; number_of_response:1; }","duration":"111.259536ms","start":"2026-07-21T21:49:17.556089Z","end":"2026-07-21T21:49:17.667348Z","steps":["trace[1999529382] 'process raft request' (duration: 110.492141ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:17.667965Z","caller":"traceutil/trace.go:171","msg":"trace[1252670169] transaction","detail":"{read_only:false; response_revision:28557; number_of_response:1; }","duration":"110.45793ms","start":"2026-07-21T21:49:17.557477Z","end":"2026-07-21T21:49:17.667935Z","steps":["trace[1252670169] 'process raft request' (duration: 110.257976ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:17.667944Z","caller":"traceutil/trace.go:171","msg":"trace[1389065324] linearizableReadLoop","detail":"{readStateIndex:29860; appliedIndex:29857; }","duration":"109.576463ms","start":"2026-07-21T21:49:17.558339Z","end":"2026-07-21T21:49:17.667916Z","steps":["trace[1389065324] 'read index received' (duration: 108.254518ms)","trace[1389065324] 'applied index is now lower than readState.Index' (duration: 1.320575ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:49:17.668205Z","caller":"traceutil/trace.go:171","msg":"trace[1722915463] transaction","detail":"{read_only:false; response_revision:28558; number_of_response:1; }","duration":"110.310437ms","start":"2026-07-21T21:49:17.557861Z","end":"2026-07-21T21:49:17.668172Z","steps":["trace[1722915463] 'process raft request' (duration: 110.007082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:17.668494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.134583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-07-21T21:49:17.668584Z","caller":"traceutil/trace.go:171","msg":"trace[557267586] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28558; }","duration":"110.299597ms","start":"2026-07-21T21:49:17.558271Z","end":"2026-07-21T21:49:17.66857Z","steps":["trace[557267586] 'agreement among raft nodes before linearized reading' (duration: 109.760457ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:23.053422Z","caller":"traceutil/trace.go:171","msg":"trace[2078975044] transaction","detail":"{read_only:false; response_revision:28603; number_of_response:1; }","duration":"204.729722ms","start":"2026-07-21T21:49:22.848646Z","end":"2026-07-21T21:49:23.053376Z","steps":["trace[2078975044] 'process raft request' (duration: 204.558699ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:23.385612Z","caller":"traceutil/trace.go:171","msg":"trace[1715238202] linearizableReadLoop","detail":"{readStateIndex:29908; appliedIndex:29907; }","duration":"332.247728ms","start":"2026-07-21T21:49:23.053343Z","end":"2026-07-21T21:49:23.38559Z","steps":["trace[1715238202] 'read index received' (duration: 259.627287ms)","trace[1715238202] 'applied index is now lower than readState.Index' (duration: 72.619771ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:49:23.385643Z","caller":"traceutil/trace.go:171","msg":"trace[1195222510] transaction","detail":"{read_only:false; response_revision:28604; number_of_response:1; }","duration":"394.722684ms","start":"2026-07-21T21:49:22.990895Z","end":"2026-07-21T21:49:23.385618Z","steps":["trace[1195222510] 'process raft request' (duration: 322.048923ms)","trace[1195222510] 'compare' (duration: 72.559099ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:49:23.38573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.601007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-21T21:49:23.385757Z","caller":"traceutil/trace.go:171","msg":"trace[1386885095] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:28604; }","duration":"379.661919ms","start":"2026-07-21T21:49:23.006089Z","end":"2026-07-21T21:49:23.385751Z","steps":["trace[1386885095] 'agreement among raft nodes before linearized reading' (duration: 379.581527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:23.385753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:22.990856Z","time spent":"394.830497ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:49:23.385773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:23.006074Z","time spent":"379.694069ms","remote":"127.0.0.1:34210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":20,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:49:23.385923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.316841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-21T21:49:23.385937Z","caller":"traceutil/trace.go:171","msg":"trace[1964410578] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:28604; }","duration":"420.345422ms","start":"2026-07-21T21:49:22.965588Z","end":"2026-07-21T21:49:23.385933Z","steps":["trace[1964410578] 'agreement among raft nodes before linearized reading' (duration: 420.316082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:23.38595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:22.965581Z","time spent":"420.365112ms","remote":"127.0.0.1:34018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:49:23.859939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.857899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:6"} {"level":"info","ts":"2026-07-21T21:49:23.860198Z","caller":"traceutil/trace.go:171","msg":"trace[2109781742] transaction","detail":"{read_only:false; number_of_response:0; response_revision:28604; }","duration":"798.075074ms","start":"2026-07-21T21:49:23.062097Z","end":"2026-07-21T21:49:23.860172Z","steps":["trace[2109781742] 'process raft request' (duration: 525.889438ms)","trace[2109781742] 'compare' (duration: 271.785498ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:49:23.860298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:23.06207Z","time spent":"798.190645ms","remote":"127.0.0.1:34206","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T21:49:23.860879Z","caller":"traceutil/trace.go:171","msg":"trace[2137253509] linearizableReadLoop","detail":"{readStateIndex:29910; appliedIndex:29908; }","duration":"475.198634ms","start":"2026-07-21T21:49:23.385653Z","end":"2026-07-21T21:49:23.860852Z","steps":["trace[2137253509] 'read index received' (duration: 202.257695ms)","trace[2137253509] 'applied index is now lower than readState.Index' (duration: 272.939379ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:49:23.860915Z","caller":"traceutil/trace.go:171","msg":"trace[1575697657] transaction","detail":"{read_only:false; response_revision:28605; number_of_response:1; }","duration":"712.993266ms","start":"2026-07-21T21:49:23.147887Z","end":"2026-07-21T21:49:23.86088Z","steps":["trace[1575697657] 'process raft request' (duration: 712.18758ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:23.860967Z","caller":"traceutil/trace.go:171","msg":"trace[243432341] transaction","detail":"{read_only:false; response_revision:28606; number_of_response:1; }","duration":"288.529016ms","start":"2026-07-21T21:49:23.572413Z","end":"2026-07-21T21:49:23.860942Z","steps":["trace[243432341] 'process raft request' (duration: 288.338572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:23.861078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:23.147845Z","time spent":"713.141069ms","remote":"127.0.0.1:33990","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-07-21T21:49:23.86107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.034078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:49:23.861151Z","caller":"traceutil/trace.go:171","msg":"trace[1142669230] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:28606; }","duration":"793.133839ms","start":"2026-07-21T21:49:23.067997Z","end":"2026-07-21T21:49:23.86113Z","steps":["trace[1142669230] 'agreement among raft nodes before linearized reading' (duration: 792.997787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:23.861214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:23.067957Z","time spent":"793.239621ms","remote":"127.0.0.1:33122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:49:23.861182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.860739ms","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-07-21T21:49:23.861315Z","caller":"traceutil/trace.go:171","msg":"trace[850122007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28606; }","duration":"586.038881ms","start":"2026-07-21T21:49:23.275251Z","end":"2026-07-21T21:49:23.861289Z","steps":["trace[850122007] 'agreement among raft nodes before linearized reading' (duration: 585.620464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:23.861398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:23.275233Z","time spent":"586.147543ms","remote":"127.0.0.1:33990","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-07-21T21:49:23.889455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.094031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-21T21:49:23.889537Z","caller":"traceutil/trace.go:171","msg":"trace[1130629953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28609; }","duration":"203.212415ms","start":"2026-07-21T21:49:23.6863Z","end":"2026-07-21T21:49:23.889512Z","steps":["trace[1130629953] 'agreement among raft nodes before linearized reading' (duration: 202.98129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:23.889546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.188768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-21T21:49:23.889554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.014502ms","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-07-21T21:49:23.889604Z","caller":"traceutil/trace.go:171","msg":"trace[640062345] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:28609; }","duration":"381.29298ms","start":"2026-07-21T21:49:23.508296Z","end":"2026-07-21T21:49:23.889589Z","steps":["trace[640062345] 'agreement among raft nodes before linearized reading' (duration: 381.161147ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:23.889612Z","caller":"traceutil/trace.go:171","msg":"trace[2117140075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28609; }","duration":"424.116253ms","start":"2026-07-21T21:49:23.465482Z","end":"2026-07-21T21:49:23.889598Z","steps":["trace[2117140075] 'agreement among raft nodes before linearized reading' (duration: 423.946569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:23.88965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:23.508274Z","time spent":"381.367881ms","remote":"127.0.0.1:34014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":19,"response size":31,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:49:23.889715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.749786ms","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-07-21T21:49:23.889756Z","caller":"traceutil/trace.go:171","msg":"trace[1772510162] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:28609; }","duration":"383.839348ms","start":"2026-07-21T21:49:23.505904Z","end":"2026-07-21T21:49:23.889744Z","steps":["trace[1772510162] 'agreement among raft nodes before linearized reading' (duration: 383.766587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:23.88979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:23.505886Z","time spent":"383.894009ms","remote":"127.0.0.1:54802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:49:23.889651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:23.465465Z","time spent":"424.177614ms","remote":"127.0.0.1:33990","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-07-21T21:49:23.889857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.147065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-07-21T21:49:23.889905Z","caller":"traceutil/trace.go:171","msg":"trace[287194788] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28609; }","duration":"200.216697ms","start":"2026-07-21T21:49:23.689678Z","end":"2026-07-21T21:49:23.889895Z","steps":["trace[287194788] 'agreement among raft nodes before linearized reading' (duration: 200.024433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:23.890023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.598589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-21T21:49:23.890149Z","caller":"traceutil/trace.go:171","msg":"trace[1945811418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28609; }","duration":"196.741001ms","start":"2026-07-21T21:49:23.693384Z","end":"2026-07-21T21:49:23.890125Z","steps":["trace[1945811418] 'agreement among raft nodes before linearized reading' (duration: 196.017717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:23.890197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.391387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-07-21T21:49:23.890411Z","caller":"traceutil/trace.go:171","msg":"trace[719409532] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28609; }","duration":"173.622391ms","start":"2026-07-21T21:49:23.716775Z","end":"2026-07-21T21:49:23.890398Z","steps":["trace[719409532] 'agreement among raft nodes before linearized reading' (duration: 172.578951ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:24.312492Z","caller":"traceutil/trace.go:171","msg":"trace[252676778] transaction","detail":"{read_only:false; response_revision:28611; number_of_response:1; }","duration":"417.08096ms","start":"2026-07-21T21:49:23.895378Z","end":"2026-07-21T21:49:24.312458Z","steps":["trace[252676778] 'process raft request' (duration: 416.763003ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:24.312492Z","caller":"traceutil/trace.go:171","msg":"trace[2107842254] transaction","detail":"{read_only:false; response_revision:28612; number_of_response:1; }","duration":"416.941667ms","start":"2026-07-21T21:49:23.895517Z","end":"2026-07-21T21:49:24.312459Z","steps":["trace[2107842254] 'process raft request' (duration: 416.843795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:24.31268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:23.895366Z","time spent":"417.195642ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:49:24.312705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:23.895498Z","time spent":"417.118751ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:49:24.312776Z","caller":"traceutil/trace.go:171","msg":"trace[2122862445] linearizableReadLoop","detail":"{readStateIndex:29919; appliedIndex:29915; }","duration":"399.484146ms","start":"2026-07-21T21:49:23.913267Z","end":"2026-07-21T21:49:24.312752Z","steps":["trace[2122862445] 'read index received' (duration: 398.917895ms)","trace[2122862445] 'applied index is now lower than readState.Index' (duration: 565.271µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:49:24.312811Z","caller":"traceutil/trace.go:171","msg":"trace[1119444639] transaction","detail":"{read_only:false; response_revision:28614; number_of_response:1; }","duration":"414.049642ms","start":"2026-07-21T21:49:23.898748Z","end":"2026-07-21T21:49:24.312798Z","steps":["trace[1119444639] 'process raft request' (duration: 413.888129ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:24.312801Z","caller":"traceutil/trace.go:171","msg":"trace[1130519626] transaction","detail":"{read_only:false; response_revision:28613; number_of_response:1; }","duration":"417.11293ms","start":"2026-07-21T21:49:23.895634Z","end":"2026-07-21T21:49:24.312747Z","steps":["trace[1130519626] 'process raft request' (duration: 416.782984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:24.312876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:23.898741Z","time spent":"414.097093ms","remote":"127.0.0.1:34206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16842,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:49:24.312979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.284304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.152\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-21T21:49:24.313024Z","caller":"traceutil/trace.go:171","msg":"trace[1276960690] range","detail":"{range_begin:/registry/masterleases/199.19.213.152; range_end:; response_count:1; response_revision:28614; }","duration":"416.357266ms","start":"2026-07-21T21:49:23.896655Z","end":"2026-07-21T21:49:24.313012Z","steps":["trace[1276960690] 'agreement among raft nodes before linearized reading' (duration: 416.212003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:24.313058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:23.896647Z","time spent":"416.401127ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/199.19.213.152\" "} {"level":"warn","ts":"2026-07-21T21:49:24.313049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:23.895622Z","time spent":"417.282213ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:49:24.32783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.57628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-07-21T21:49:24.3279Z","caller":"traceutil/trace.go:171","msg":"trace[1051954594] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28615; }","duration":"235.689002ms","start":"2026-07-21T21:49:24.092195Z","end":"2026-07-21T21:49:24.327884Z","steps":["trace[1051954594] 'agreement among raft nodes before linearized reading' (duration: 235.493788ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:24.328194Z","caller":"traceutil/trace.go:171","msg":"trace[158880848] transaction","detail":"{read_only:false; response_revision:28615; number_of_response:1; }","duration":"410.831522ms","start":"2026-07-21T21:49:23.917353Z","end":"2026-07-21T21:49:24.328184Z","steps":["trace[158880848] 'process raft request' (duration: 410.216669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:24.328271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:23.917332Z","time spent":"410.880982ms","remote":"127.0.0.1:33808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:49:24.3285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.108173ms","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-07-21T21:49:24.32852Z","caller":"traceutil/trace.go:171","msg":"trace[609810626] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28615; }","duration":"231.154575ms","start":"2026-07-21T21:49:24.097359Z","end":"2026-07-21T21:49:24.328513Z","steps":["trace[609810626] 'agreement among raft nodes before linearized reading' (duration: 231.095154ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:24.500292Z","caller":"traceutil/trace.go:171","msg":"trace[164072767] linearizableReadLoop","detail":"{readStateIndex:29923; appliedIndex:29922; }","duration":"134.250423ms","start":"2026-07-21T21:49:24.366018Z","end":"2026-07-21T21:49:24.500269Z","steps":["trace[164072767] 'read index received' (duration: 134.212262ms)","trace[164072767] 'applied index is now lower than readState.Index' (duration: 37.191µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:49:24.50037Z","caller":"traceutil/trace.go:171","msg":"trace[167828283] transaction","detail":"{read_only:false; response_revision:28617; number_of_response:1; }","duration":"156.037467ms","start":"2026-07-21T21:49:24.344296Z","end":"2026-07-21T21:49:24.500333Z","steps":["trace[167828283] 'process raft request' (duration: 155.827093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:24.500517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.914003ms","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-07-21T21:49:24.500546Z","caller":"traceutil/trace.go:171","msg":"trace[1300675410] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28617; }","duration":"157.971613ms","start":"2026-07-21T21:49:24.342563Z","end":"2026-07-21T21:49:24.500534Z","steps":["trace[1300675410] 'agreement among raft nodes before linearized reading' (duration: 157.815581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:24.500584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.337233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-07-21T21:49:24.500657Z","caller":"traceutil/trace.go:171","msg":"trace[2136065878] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28617; }","duration":"146.441424ms","start":"2026-07-21T21:49:24.354196Z","end":"2026-07-21T21:49:24.500637Z","steps":["trace[2136065878] 'agreement among raft nodes before linearized reading' (duration: 146.142469ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:24.520494Z","caller":"traceutil/trace.go:171","msg":"trace[1955390358] transaction","detail":"{read_only:false; response_revision:28618; number_of_response:1; }","duration":"149.841838ms","start":"2026-07-21T21:49:24.370633Z","end":"2026-07-21T21:49:24.520475Z","steps":["trace[1955390358] 'process raft request' (duration: 149.632404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:24.520687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.587432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-07-21T21:49:24.520768Z","caller":"traceutil/trace.go:171","msg":"trace[370805931] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:28618; }","duration":"152.705723ms","start":"2026-07-21T21:49:24.36804Z","end":"2026-07-21T21:49:24.520746Z","steps":["trace[370805931] 'agreement among raft nodes before linearized reading' (duration: 152.461599ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:24.686349Z","caller":"traceutil/trace.go:171","msg":"trace[1340852741] linearizableReadLoop","detail":"{readStateIndex:29926; appliedIndex:29925; }","duration":"139.835779ms","start":"2026-07-21T21:49:24.546477Z","end":"2026-07-21T21:49:24.686313Z","steps":["trace[1340852741] 'read index received' (duration: 126.877332ms)","trace[1340852741] 'applied index is now lower than readState.Index' (duration: 12.956677ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:49:24.686414Z","caller":"traceutil/trace.go:171","msg":"trace[785572808] transaction","detail":"{read_only:false; response_revision:28620; number_of_response:1; }","duration":"147.880972ms","start":"2026-07-21T21:49:24.53849Z","end":"2026-07-21T21:49:24.686371Z","steps":["trace[785572808] 'process raft request' (duration: 135.011887ms)","trace[785572808] 'compare' (duration: 12.557758ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:49:24.686614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.109704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4831"} {"level":"info","ts":"2026-07-21T21:49:24.686665Z","caller":"traceutil/trace.go:171","msg":"trace[919098693] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:28620; }","duration":"140.223086ms","start":"2026-07-21T21:49:24.546425Z","end":"2026-07-21T21:49:24.686648Z","steps":["trace[919098693] 'agreement among raft nodes before linearized reading' (duration: 140.003502ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:25.547155Z","caller":"traceutil/trace.go:171","msg":"trace[123110234] transaction","detail":"{read_only:false; response_revision:28621; number_of_response:1; }","duration":"156.135198ms","start":"2026-07-21T21:49:25.390977Z","end":"2026-07-21T21:49:25.547112Z","steps":["trace[123110234] 'process raft request' (duration: 155.749511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:26.393787Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13272283501825630277,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T21:49:26.751511Z","caller":"traceutil/trace.go:171","msg":"trace[103260004] transaction","detail":"{read_only:false; response_revision:28622; number_of_response:1; }","duration":"884.734351ms","start":"2026-07-21T21:49:25.866756Z","end":"2026-07-21T21:49:26.751491Z","steps":["trace[103260004] 'process raft request' (duration: 884.561847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:26.751623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:25.866736Z","time spent":"884.825123ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:49:26.905836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.904074ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:49:26.906334Z","caller":"traceutil/trace.go:171","msg":"trace[1882616052] transaction","detail":"{read_only:false; response_revision:28625; number_of_response:1; }","duration":"1.009110736s","start":"2026-07-21T21:49:25.897193Z","end":"2026-07-21T21:49:26.906304Z","steps":["trace[1882616052] 'process raft request' (duration: 1.008902332s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:26.906474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:25.897165Z","time spent":"1.009238628s","remote":"127.0.0.1:33990","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-07-21T21:49:26.906623Z","caller":"traceutil/trace.go:171","msg":"trace[1000143515] transaction","detail":"{read_only:false; response_revision:28624; number_of_response:1; }","duration":"1.013590001s","start":"2026-07-21T21:49:25.893013Z","end":"2026-07-21T21:49:26.906603Z","steps":["trace[1000143515] 'process raft request' (duration: 1.012971069s)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:26.906591Z","caller":"traceutil/trace.go:171","msg":"trace[845319822] transaction","detail":"{read_only:false; response_revision:28623; number_of_response:1; }","duration":"1.039602905s","start":"2026-07-21T21:49:25.866939Z","end":"2026-07-21T21:49:26.906542Z","steps":["trace[845319822] 'process raft request' (duration: 902.419847ms)","trace[845319822] 'compare' (duration: 135.748091ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:49:26.906747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:25.892989Z","time spent":"1.013684053s","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:49:26.906767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:25.866892Z","time spent":"1.039795398s","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:49:26.906932Z","caller":"traceutil/trace.go:171","msg":"trace[401678595] transaction","detail":"{read_only:false; response_revision:28626; number_of_response:1; }","duration":"618.262385ms","start":"2026-07-21T21:49:26.288664Z","end":"2026-07-21T21:49:26.906926Z","steps":["trace[401678595] 'process raft request' (duration: 617.527951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:26.906967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.288633Z","time spent":"618.316576ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:49:26.906914Z","caller":"traceutil/trace.go:171","msg":"trace[1070050444] linearizableReadLoop","detail":"{readStateIndex:29932; appliedIndex:29927; }","duration":"1.013235754s","start":"2026-07-21T21:49:25.893663Z","end":"2026-07-21T21:49:26.906898Z","steps":["trace[1070050444] 'read index received' (duration: 857.713887ms)","trace[1070050444] 'applied index is now lower than readState.Index' (duration: 155.520477ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:49:26.90707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013398698s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-21T21:49:26.907124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.054123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-07-21T21:49:26.907122Z","caller":"traceutil/trace.go:171","msg":"trace[1337894358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28626; }","duration":"1.013483669s","start":"2026-07-21T21:49:25.893627Z","end":"2026-07-21T21:49:26.907111Z","steps":["trace[1337894358] 'agreement among raft nodes before linearized reading' (duration: 1.013319536s)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:26.907154Z","caller":"traceutil/trace.go:171","msg":"trace[1827750651] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28626; }","duration":"990.128515ms","start":"2026-07-21T21:49:25.91702Z","end":"2026-07-21T21:49:26.907148Z","steps":["trace[1827750651] 'agreement among raft nodes before linearized reading' (duration: 990.041813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:26.90717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:25.916997Z","time spent":"990.169365ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":550,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-07-21T21:49:26.907169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:25.893616Z","time spent":"1.01354393s","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T21:49:26.907173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.123897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-21T21:49:26.907217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.294211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:49:26.907227Z","caller":"traceutil/trace.go:171","msg":"trace[1819426640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28626; }","duration":"563.318311ms","start":"2026-07-21T21:49:26.343905Z","end":"2026-07-21T21:49:26.907224Z","steps":["trace[1819426640] 'agreement among raft nodes before linearized reading' (duration: 563.297151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:26.907239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.343895Z","time spent":"563.341201ms","remote":"127.0.0.1:33672","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-07-21T21:49:26.907229Z","caller":"traceutil/trace.go:171","msg":"trace[1875410735] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:28626; }","duration":"563.190468ms","start":"2026-07-21T21:49:26.344021Z","end":"2026-07-21T21:49:26.907212Z","steps":["trace[1875410735] 'agreement among raft nodes before linearized reading' (duration: 563.098186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:26.907276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.344016Z","time spent":"563.246229ms","remote":"127.0.0.1:47606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:49:26.907344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.016477ms","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-07-21T21:49:26.907357Z","caller":"traceutil/trace.go:171","msg":"trace[1782226615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28626; }","duration":"580.034167ms","start":"2026-07-21T21:49:26.327318Z","end":"2026-07-21T21:49:26.907352Z","steps":["trace[1782226615] 'agreement among raft nodes before linearized reading' (duration: 579.993857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:26.90735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.667721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"warn","ts":"2026-07-21T21:49:26.90737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.327314Z","time spent":"580.051958ms","remote":"127.0.0.1:33990","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":"info","ts":"2026-07-21T21:49:26.90739Z","caller":"traceutil/trace.go:171","msg":"trace[77630608] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28626; }","duration":"367.751983ms","start":"2026-07-21T21:49:26.539624Z","end":"2026-07-21T21:49:26.907376Z","steps":["trace[77630608] 'agreement among raft nodes before linearized reading' (duration: 367.63163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:26.907384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.886363ms","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-07-21T21:49:26.907422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.112269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-21T21:49:26.907435Z","caller":"traceutil/trace.go:171","msg":"trace[728436529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28626; }","duration":"580.12707ms","start":"2026-07-21T21:49:26.327304Z","end":"2026-07-21T21:49:26.907431Z","steps":["trace[728436529] 'agreement among raft nodes before linearized reading' (duration: 580.093619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:26.907428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.539601Z","time spent":"367.817223ms","remote":"127.0.0.1:33808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":670,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-21T21:49:26.907438Z","caller":"traceutil/trace.go:171","msg":"trace[2031311935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28626; }","duration":"399.979525ms","start":"2026-07-21T21:49:26.507446Z","end":"2026-07-21T21:49:26.907425Z","steps":["trace[2031311935] 'agreement among raft nodes before linearized reading' (duration: 399.838322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:26.907471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.507432Z","time spent":"400.033916ms","remote":"127.0.0.1:33990","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":"warn","ts":"2026-07-21T21:49:26.907484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.208671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-21T21:49:26.907499Z","caller":"traceutil/trace.go:171","msg":"trace[855033609] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28626; }","duration":"580.231151ms","start":"2026-07-21T21:49:26.327264Z","end":"2026-07-21T21:49:26.907495Z","steps":["trace[855033609] 'agreement among raft nodes before linearized reading' (duration: 580.19178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:26.907511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.32726Z","time spent":"580.248851ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-21T21:49:26.907531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.369377ms","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":"warn","ts":"2026-07-21T21:49:26.907569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.515708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:49:26.907574Z","caller":"traceutil/trace.go:171","msg":"trace[1546273497] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:28626; }","duration":"455.442789ms","start":"2026-07-21T21:49:26.452119Z","end":"2026-07-21T21:49:26.907562Z","steps":["trace[1546273497] 'agreement among raft nodes before linearized reading' (duration: 455.344907ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:26.907593Z","caller":"traceutil/trace.go:171","msg":"trace[1360846460] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:28626; }","duration":"397.552009ms","start":"2026-07-21T21:49:26.510032Z","end":"2026-07-21T21:49:26.907584Z","steps":["trace[1360846460] 'agreement among raft nodes before linearized reading' (duration: 397.505458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:26.907624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.510027Z","time spent":"397.592399ms","remote":"127.0.0.1:54778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:49:26.907633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.557688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-21T21:49:26.907649Z","caller":"traceutil/trace.go:171","msg":"trace[233140264] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:28626; }","duration":"580.61063ms","start":"2026-07-21T21:49:26.327034Z","end":"2026-07-21T21:49:26.907644Z","steps":["trace[233140264] 'agreement among raft nodes before linearized reading' (duration: 580.510808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:26.907662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.327019Z","time spent":"580.63848ms","remote":"127.0.0.1:33808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":146,"response size":32,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:49:26.907447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.32729Z","time spent":"580.15439ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T21:49:26.90775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.997547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-21T21:49:26.907737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.373263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-07-21T21:49:26.907766Z","caller":"traceutil/trace.go:171","msg":"trace[1694420593] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:28626; }","duration":"680.06619ms","start":"2026-07-21T21:49:26.227694Z","end":"2026-07-21T21:49:26.90776Z","steps":["trace[1694420593] 'agreement among raft nodes before linearized reading' (duration: 680.040989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:26.90778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.227671Z","time spent":"680.105841ms","remote":"127.0.0.1:48342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"info","ts":"2026-07-21T21:49:26.907793Z","caller":"traceutil/trace.go:171","msg":"trace[1122395527] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28626; }","duration":"217.478605ms","start":"2026-07-21T21:49:26.690299Z","end":"2026-07-21T21:49:26.907778Z","steps":["trace[1122395527] 'agreement among raft nodes before linearized reading' (duration: 217.344583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:26.907857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.783717ms","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-07-21T21:49:26.907874Z","caller":"traceutil/trace.go:171","msg":"trace[1468652393] 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:28626; }","duration":"726.847049ms","start":"2026-07-21T21:49:26.18102Z","end":"2026-07-21T21:49:26.907867Z","steps":["trace[1468652393] 'agreement among raft nodes before linearized reading' (duration: 726.824308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:26.907887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.180996Z","time spent":"726.8879ms","remote":"127.0.0.1:47656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-07-21T21:49:26.907618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.452094Z","time spent":"455.513ms","remote":"127.0.0.1:54812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:49:26.907982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.351663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"warn","ts":"2026-07-21T21:49:26.907989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.243611ms","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-07-21T21:49:26.907997Z","caller":"traceutil/trace.go:171","msg":"trace[1907999003] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:28626; }","duration":"864.416994ms","start":"2026-07-21T21:49:26.043574Z","end":"2026-07-21T21:49:26.907991Z","steps":["trace[1907999003] 'agreement among raft nodes before linearized reading' (duration: 864.377213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:26.908011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.04355Z","time spent":"864.457105ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":584,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-07-21T21:49:26.908014Z","caller":"traceutil/trace.go:171","msg":"trace[1552772572] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:28626; }","duration":"226.317312ms","start":"2026-07-21T21:49:26.681687Z","end":"2026-07-21T21:49:26.908004Z","steps":["trace[1552772572] 'agreement among raft nodes before linearized reading' (duration: 226.274251ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:27.064504Z","caller":"traceutil/trace.go:171","msg":"trace[1717923230] transaction","detail":"{read_only:false; response_revision:28627; number_of_response:1; }","duration":"151.547071ms","start":"2026-07-21T21:49:26.912933Z","end":"2026-07-21T21:49:27.06448Z","steps":["trace[1717923230] 'process raft request' (duration: 151.387068ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:27.064776Z","caller":"traceutil/trace.go:171","msg":"trace[1027677073] transaction","detail":"{read_only:false; response_revision:28630; number_of_response:1; }","duration":"151.642623ms","start":"2026-07-21T21:49:26.913103Z","end":"2026-07-21T21:49:27.064745Z","steps":["trace[1027677073] 'process raft request' (duration: 151.603703ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:27.064854Z","caller":"traceutil/trace.go:171","msg":"trace[1257413676] transaction","detail":"{read_only:false; response_revision:28629; number_of_response:1; }","duration":"151.777076ms","start":"2026-07-21T21:49:26.913049Z","end":"2026-07-21T21:49:27.064826Z","steps":["trace[1257413676] 'process raft request' (duration: 151.586752ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:27.064905Z","caller":"traceutil/trace.go:171","msg":"trace[258207013] transaction","detail":"{read_only:false; response_revision:28628; number_of_response:1; }","duration":"151.846626ms","start":"2026-07-21T21:49:26.913019Z","end":"2026-07-21T21:49:27.064865Z","steps":["trace[258207013] 'process raft request' (duration: 151.416789ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:27.241176Z","caller":"traceutil/trace.go:171","msg":"trace[1345369146] transaction","detail":"{read_only:false; response_revision:28632; number_of_response:1; }","duration":"327.097279ms","start":"2026-07-21T21:49:26.914034Z","end":"2026-07-21T21:49:27.241131Z","steps":["trace[1345369146] 'process raft request' (duration: 326.946586ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:27.241299Z","caller":"traceutil/trace.go:171","msg":"trace[1286142348] linearizableReadLoop","detail":"{readStateIndex:29941; appliedIndex:29933; }","duration":"322.513801ms","start":"2026-07-21T21:49:26.918751Z","end":"2026-07-21T21:49:27.241265Z","steps":["trace[1286142348] 'read index received' (duration: 145.6796ms)","trace[1286142348] 'applied index is now lower than readState.Index' (duration: 176.832481ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:49:27.241401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.914022Z","time spent":"327.267082ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:49:27.241487Z","caller":"traceutil/trace.go:171","msg":"trace[988772814] transaction","detail":"{read_only:false; response_revision:28633; number_of_response:1; }","duration":"327.280723ms","start":"2026-07-21T21:49:26.914192Z","end":"2026-07-21T21:49:27.241473Z","steps":["trace[988772814] 'process raft request' (duration: 326.853734ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:27.241177Z","caller":"traceutil/trace.go:171","msg":"trace[547394401] transaction","detail":"{read_only:false; response_revision:28631; number_of_response:1; }","duration":"327.097919ms","start":"2026-07-21T21:49:26.914029Z","end":"2026-07-21T21:49:27.241127Z","steps":["trace[547394401] 'process raft request' (duration: 306.504318ms)","trace[547394401] 'compare' (duration: 20.189893ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:49:27.241645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.914006Z","time spent":"327.567448ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:49:27.241738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.914173Z","time spent":"327.354014ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:49:27.241788Z","caller":"traceutil/trace.go:171","msg":"trace[1853588369] transaction","detail":"{read_only:false; response_revision:28636; number_of_response:1; }","duration":"322.693835ms","start":"2026-07-21T21:49:26.919059Z","end":"2026-07-21T21:49:27.241753Z","steps":["trace[1853588369] 'process raft request' (duration: 322.133854ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:27.24196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.919048Z","time spent":"322.830228ms","remote":"127.0.0.1:33808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:49:27.241993Z","caller":"traceutil/trace.go:171","msg":"trace[563195229] transaction","detail":"{read_only:false; response_revision:28635; number_of_response:1; }","duration":"325.192242ms","start":"2026-07-21T21:49:26.916775Z","end":"2026-07-21T21:49:27.241967Z","steps":["trace[563195229] 'process raft request' (duration: 324.361276ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:27.242041Z","caller":"traceutil/trace.go:171","msg":"trace[1716209259] transaction","detail":"{read_only:false; response_revision:28634; number_of_response:1; }","duration":"327.67152ms","start":"2026-07-21T21:49:26.914345Z","end":"2026-07-21T21:49:27.242016Z","steps":["trace[1716209259] 'process raft request' (duration: 326.740512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:27.242047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.794666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-21T21:49:27.242085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.916765Z","time spent":"325.276623ms","remote":"127.0.0.1:33808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:49:27.242104Z","caller":"traceutil/trace.go:171","msg":"trace[2111342395] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:28636; }","duration":"211.913478ms","start":"2026-07-21T21:49:27.030176Z","end":"2026-07-21T21:49:27.242089Z","steps":["trace[2111342395] 'agreement among raft nodes before linearized reading' (duration: 211.801296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:27.242134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.914315Z","time spent":"327.771171ms","remote":"127.0.0.1:33990","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-07-21T21:49:27.242155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.402138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:49:27.242186Z","caller":"traceutil/trace.go:171","msg":"trace[2008407399] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:28636; }","duration":"323.46552ms","start":"2026-07-21T21:49:26.918712Z","end":"2026-07-21T21:49:27.242177Z","steps":["trace[2008407399] 'agreement among raft nodes before linearized reading' (duration: 323.414109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:27.242219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:26.918697Z","time spent":"323.514721ms","remote":"127.0.0.1:33912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-07-21T21:49:27.559011Z","caller":"traceutil/trace.go:171","msg":"trace[1790050181] linearizableReadLoop","detail":"{readStateIndex:29944; appliedIndex:29943; }","duration":"291.154066ms","start":"2026-07-21T21:49:27.267829Z","end":"2026-07-21T21:49:27.558983Z","steps":["trace[1790050181] 'read index received' (duration: 290.943262ms)","trace[1790050181] 'applied index is now lower than readState.Index' (duration: 209.784µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:49:27.559143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.295649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:49:27.559179Z","caller":"traceutil/trace.go:171","msg":"trace[696144137] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:28638; }","duration":"291.37825ms","start":"2026-07-21T21:49:27.267787Z","end":"2026-07-21T21:49:27.559165Z","steps":["trace[696144137] 'agreement among raft nodes before linearized reading' (duration: 291.295568ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:27.559248Z","caller":"traceutil/trace.go:171","msg":"trace[1474227315] transaction","detail":"{read_only:false; response_revision:28638; number_of_response:1; }","duration":"308.323783ms","start":"2026-07-21T21:49:27.250896Z","end":"2026-07-21T21:49:27.559219Z","steps":["trace[1474227315] 'process raft request' (duration: 307.922005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:27.559412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:49:27.25087Z","time spent":"308.448235ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:49:28.55017Z","caller":"traceutil/trace.go:171","msg":"trace[751902092] linearizableReadLoop","detail":"{readStateIndex:29946; appliedIndex:29945; }","duration":"110.640784ms","start":"2026-07-21T21:49:28.439505Z","end":"2026-07-21T21:49:28.550145Z","steps":["trace[751902092] 'read index received' (duration: 110.490441ms)","trace[751902092] 'applied index is now lower than readState.Index' (duration: 149.173µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:49:28.550408Z","caller":"traceutil/trace.go:171","msg":"trace[787039623] transaction","detail":"{read_only:false; response_revision:28640; number_of_response:1; }","duration":"124.17121ms","start":"2026-07-21T21:49:28.426009Z","end":"2026-07-21T21:49:28.55018Z","steps":["trace[787039623] 'process raft request' (duration: 123.972147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:49:28.550575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.055702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-07-21T21:49:28.550653Z","caller":"traceutil/trace.go:171","msg":"trace[1122612781] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:28640; }","duration":"111.182235ms","start":"2026-07-21T21:49:28.439451Z","end":"2026-07-21T21:49:28.550633Z","steps":["trace[1122612781] 'agreement among raft nodes before linearized reading' (duration: 110.95702ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:29.149741Z","caller":"traceutil/trace.go:171","msg":"trace[1429664071] transaction","detail":"{read_only:false; response_revision:28644; number_of_response:1; }","duration":"233.079581ms","start":"2026-07-21T21:49:28.91664Z","end":"2026-07-21T21:49:29.149719Z","steps":["trace[1429664071] 'process raft request' (duration: 232.899348ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:29.149854Z","caller":"traceutil/trace.go:171","msg":"trace[215190771] transaction","detail":"{read_only:false; response_revision:28643; number_of_response:1; }","duration":"234.266915ms","start":"2026-07-21T21:49:28.915557Z","end":"2026-07-21T21:49:29.149824Z","steps":["trace[215190771] 'process raft request' (duration: 232.891029ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:30.959576Z","caller":"traceutil/trace.go:171","msg":"trace[103704921] transaction","detail":"{read_only:false; response_revision:28659; number_of_response:1; }","duration":"115.165549ms","start":"2026-07-21T21:49:30.844381Z","end":"2026-07-21T21:49:30.959547Z","steps":["trace[103704921] 'process raft request' (duration: 114.955935ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:31.073963Z","caller":"traceutil/trace.go:171","msg":"trace[1464082390] transaction","detail":"{read_only:false; response_revision:28660; number_of_response:1; }","duration":"214.143191ms","start":"2026-07-21T21:49:30.859793Z","end":"2026-07-21T21:49:31.073936Z","steps":["trace[1464082390] 'process raft request' (duration: 213.549319ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:31.744476Z","caller":"traceutil/trace.go:171","msg":"trace[1857989700] transaction","detail":"{read_only:false; response_revision:28673; number_of_response:1; }","duration":"106.655528ms","start":"2026-07-21T21:49:31.637794Z","end":"2026-07-21T21:49:31.744449Z","steps":["trace[1857989700] 'process raft request' (duration: 89.522202ms)","trace[1857989700] 'compare' (duration: 17.020374ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:49:33.13554Z","caller":"traceutil/trace.go:171","msg":"trace[661147164] transaction","detail":"{read_only:false; response_revision:28676; number_of_response:1; }","duration":"171.606842ms","start":"2026-07-21T21:49:32.963914Z","end":"2026-07-21T21:49:33.135521Z","steps":["trace[661147164] 'process raft request' (duration: 171.481039ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:34.890796Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"68d6d88687e6b830","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-21T21:49:34.896256Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-21T21:49:34.896374Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-21T21:52:20.767874Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27249} {"level":"info","ts":"2026-07-21T21:52:20.923827Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27249,"took":"149.919118ms","hash":3598687428,"current-db-size-bytes":61538304,"current-db-size":"62 MB","current-db-size-in-use-bytes":37838848,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-07-21T21:52:20.924006Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3598687428,"revision":27249,"compact-revision":23642} {"level":"info","ts":"2026-07-21T21:57:20.774594Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30693} {"level":"info","ts":"2026-07-21T21:57:20.921762Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30693,"took":"143.087199ms","hash":2474127713,"current-db-size-bytes":61538304,"current-db-size":"62 MB","current-db-size-in-use-bytes":36237312,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-21T21:57:20.921846Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2474127713,"revision":30693,"compact-revision":27249} {"level":"info","ts":"2026-07-21T22:02:20.782358Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33538} {"level":"info","ts":"2026-07-21T22:02:20.928306Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33538,"took":"139.378035ms","hash":2759454227,"current-db-size-bytes":61538304,"current-db-size":"62 MB","current-db-size-in-use-bytes":34729984,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-21T22:02:20.928374Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2759454227,"revision":33538,"compact-revision":30693} {"level":"info","ts":"2026-07-21T22:05:54.855645Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"68d6d88687e6b830","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-21T22:05:54.860001Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-21T22:05:54.860116Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-21T22:07:20.789395Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36276} {"level":"info","ts":"2026-07-21T22:07:20.92538Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36276,"took":"130.313199ms","hash":3284990075,"current-db-size-bytes":61538304,"current-db-size":"62 MB","current-db-size-in-use-bytes":34856960,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-21T22:07:20.925438Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3284990075,"revision":36276,"compact-revision":33538} {"level":"info","ts":"2026-07-21T22:08:31.295377Z","caller":"traceutil/trace.go:171","msg":"trace[1855596563] transaction","detail":"{read_only:false; response_revision:39690; number_of_response:1; }","duration":"108.618293ms","start":"2026-07-21T22:08:31.186723Z","end":"2026-07-21T22:08:31.295341Z","steps":["trace[1855596563] 'process raft request' (duration: 108.42232ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T22:12:20.795644Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39021} {"level":"info","ts":"2026-07-21T22:12:20.956397Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39021,"took":"155.577892ms","hash":1191555787,"current-db-size-bytes":61538304,"current-db-size":"62 MB","current-db-size-in-use-bytes":35176448,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-21T22:12:20.956475Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1191555787,"revision":39021,"compact-revision":36276} {"level":"info","ts":"2026-07-21T22:17:20.803941Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41769} {"level":"info","ts":"2026-07-21T22:17:20.944701Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41769,"took":"136.066286ms","hash":2571168047,"current-db-size-bytes":61538304,"current-db-size":"62 MB","current-db-size-in-use-bytes":34148352,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-21T22:17:20.944765Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2571168047,"revision":41769,"compact-revision":39021}