{"level":"warn","ts":"2026-06-09T17:12:11.657542Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T17:12:11.657657Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.151: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.151:2380","--initial-cluster=instance=https://199.19.213.151:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.151:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.151: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-06-09T17:12:11.657727Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T17:12:11.65774Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.151:2380"]} {"level":"info","ts":"2026-06-09T17:12:11.65776Z","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-06-09T17:12:11.658332Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.151:2379"]} {"level":"info","ts":"2026-06-09T17:12:11.658425Z","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.151:2380"],"listen-peer-urls":["https://199.19.213.151:2380"],"advertise-client-urls":["https://199.19.213.151:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.151:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.151: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-06-09T17:12:11.661283Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.368805ms"} {"level":"info","ts":"2026-06-09T17:12:11.666927Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9abf795dae4ebc2c","cluster-id":"8a186cf11cef5bce"} {"level":"info","ts":"2026-06-09T17:12:11.667026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c switched to configuration voters=()"} {"level":"info","ts":"2026-06-09T17:12:11.667059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became follower at term 0"} {"level":"info","ts":"2026-06-09T17:12:11.667072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9abf795dae4ebc2c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-09T17:12:11.66709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became follower at term 1"} {"level":"info","ts":"2026-06-09T17:12:11.667144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c switched to configuration voters=(11150764645655952428)"} {"level":"warn","ts":"2026-06-09T17:12:11.67195Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-09T17:12:11.674612Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-09T17:12:11.676444Z","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-06-09T17:12:11.679493Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9abf795dae4ebc2c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-09T17:12:11.679609Z","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-06-09T17:12:11.679687Z","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-06-09T17:12:11.679699Z","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-06-09T17:12:11.679695Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9abf795dae4ebc2c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-09T17:12:11.680816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c switched to configuration voters=(11150764645655952428)"} {"level":"info","ts":"2026-06-09T17:12:11.681024Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8a186cf11cef5bce","local-member-id":"9abf795dae4ebc2c","added-peer-id":"9abf795dae4ebc2c","added-peer-peer-urls":["https://199.19.213.151:2380"]} {"level":"info","ts":"2026-06-09T17:12:11.6836Z","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-06-09T17:12:11.68379Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9abf795dae4ebc2c","initial-advertise-peer-urls":["https://199.19.213.151:2380"],"listen-peer-urls":["https://199.19.213.151:2380"],"advertise-client-urls":["https://199.19.213.151:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.151:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-09T17:12:11.683833Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-09T17:12:11.683756Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.151:2380"} {"level":"info","ts":"2026-06-09T17:12:11.68424Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.151:2380"} {"level":"info","ts":"2026-06-09T17:12:11.967345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c is starting a new election at term 1"} {"level":"info","ts":"2026-06-09T17:12:11.967539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became pre-candidate at term 1"} {"level":"info","ts":"2026-06-09T17:12:11.967705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c received MsgPreVoteResp from 9abf795dae4ebc2c at term 1"} {"level":"info","ts":"2026-06-09T17:12:11.9678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became candidate at term 2"} {"level":"info","ts":"2026-06-09T17:12:11.96788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c received MsgVoteResp from 9abf795dae4ebc2c at term 2"} {"level":"info","ts":"2026-06-09T17:12:11.967974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became leader at term 2"} {"level":"info","ts":"2026-06-09T17:12:11.968054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9abf795dae4ebc2c elected leader 9abf795dae4ebc2c at term 2"} {"level":"info","ts":"2026-06-09T17:12:11.96939Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:12:11.970636Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T17:12:11.970712Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T17:12:11.970637Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9abf795dae4ebc2c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.151:2379]}","request-path":"/0/members/9abf795dae4ebc2c/attributes","cluster-id":"8a186cf11cef5bce","publish-timeout":"7s"} {"level":"info","ts":"2026-06-09T17:12:11.971191Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-09T17:12:11.971247Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-09T17:12:11.971693Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8a186cf11cef5bce","local-member-id":"9abf795dae4ebc2c","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:12:11.972348Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:12:11.972447Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:12:11.974019Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.151:2379"} {"level":"info","ts":"2026-06-09T17:12:11.974383Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-09T17:12:56.550698Z","caller":"traceutil/trace.go:171","msg":"trace[360186379] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"303.096341ms","start":"2026-06-09T17:12:56.247571Z","end":"2026-06-09T17:12:56.550668Z","steps":["trace[360186379] 'process raft request' (duration: 302.761935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:12:56.551304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:12:56.247539Z","time spent":"303.274484ms","remote":"127.0.0.1:44476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:12:56.885772Z","caller":"traceutil/trace.go:171","msg":"trace[1881536676] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"236.648675ms","start":"2026-06-09T17:12:56.649083Z","end":"2026-06-09T17:12:56.885732Z","steps":["trace[1881536676] 'process raft request' (duration: 230.327976ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:13:11.720736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.2934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:13:11.720917Z","caller":"traceutil/trace.go:171","msg":"trace[1711760389] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"233.425424ms","start":"2026-06-09T17:13:11.487467Z","end":"2026-06-09T17:13:11.720893Z","steps":["trace[1711760389] 'process raft request' (duration: 91.662264ms)","trace[1711760389] 'compare' (duration: 141.114527ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:14:25.35608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.668554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.151\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-09T17:14:25.356743Z","caller":"traceutil/trace.go:171","msg":"trace[1074366485] range","detail":"{range_begin:/registry/masterleases/199.19.213.151; range_end:; response_count:1; response_revision:1845; }","duration":"198.393409ms","start":"2026-06-09T17:14:25.158324Z","end":"2026-06-09T17:14:25.356717Z","steps":["trace[1074366485] 'range keys from in-memory index tree' (duration: 197.355828ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:14:25.862542Z","caller":"traceutil/trace.go:171","msg":"trace[1533553715] linearizableReadLoop","detail":"{readStateIndex:1923; appliedIndex:1921; }","duration":"131.082547ms","start":"2026-06-09T17:14:25.73143Z","end":"2026-06-09T17:14:25.862512Z","steps":["trace[1533553715] 'read index received' (duration: 33.356196ms)","trace[1533553715] 'applied index is now lower than readState.Index' (duration: 97.721291ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:14:25.862692Z","caller":"traceutil/trace.go:171","msg":"trace[1352658318] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"153.933061ms","start":"2026-06-09T17:14:25.708712Z","end":"2026-06-09T17:14:25.862645Z","steps":["trace[1352658318] 'process raft request' (duration: 147.800705ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:14:25.862803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.353471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9ddfdbcb-a302-4915-8038-d684cf3bc1ed\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-09T17:14:25.862849Z","caller":"traceutil/trace.go:171","msg":"trace[787032259] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9ddfdbcb-a302-4915-8038-d684cf3bc1ed; range_end:; response_count:1; response_revision:1848; }","duration":"131.480794ms","start":"2026-06-09T17:14:25.731353Z","end":"2026-06-09T17:14:25.862834Z","steps":["trace[787032259] 'agreement among raft nodes before linearized reading' (duration: 131.27642ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:14:35.032663Z","caller":"traceutil/trace.go:171","msg":"trace[1429630533] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"106.915496ms","start":"2026-06-09T17:14:34.925686Z","end":"2026-06-09T17:14:35.032602Z","steps":["trace[1429630533] 'process raft request' (duration: 106.701772ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:16:38.940727Z","caller":"traceutil/trace.go:171","msg":"trace[694546024] transaction","detail":"{read_only:false; response_revision:2817; number_of_response:1; }","duration":"113.058019ms","start":"2026-06-09T17:16:38.827636Z","end":"2026-06-09T17:16:38.940694Z","steps":["trace[694546024] 'process raft request' (duration: 112.867426ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:16:39.262716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.757134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:16:39.262899Z","caller":"traceutil/trace.go:171","msg":"trace[1070114121] transaction","detail":"{read_only:false; response_revision:2818; number_of_response:1; }","duration":"317.919943ms","start":"2026-06-09T17:16:38.944904Z","end":"2026-06-09T17:16:39.262824Z","steps":["trace[1070114121] 'process raft request' (duration: 43.969326ms)","trace[1070114121] 'compare' (duration: 273.652221ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:16:39.263732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:16:38.944883Z","time spent":"318.658156ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:19:15.338092Z","caller":"traceutil/trace.go:171","msg":"trace[663191757] transaction","detail":"{read_only:false; response_revision:3840; number_of_response:1; }","duration":"102.505429ms","start":"2026-06-09T17:19:15.235557Z","end":"2026-06-09T17:19:15.338062Z","steps":["trace[663191757] 'process raft request' (duration: 88.588663ms)","trace[663191757] 'compare' (duration: 13.789773ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:20:33.450621Z","caller":"traceutil/trace.go:171","msg":"trace[1138109089] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"108.388274ms","start":"2026-06-09T17:20:33.34221Z","end":"2026-06-09T17:20:33.450598Z","steps":["trace[1138109089] 'process raft request' (duration: 108.17406ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:20:35.656374Z","caller":"traceutil/trace.go:171","msg":"trace[1442500728] linearizableReadLoop","detail":"{readStateIndex:5175; appliedIndex:5174; }","duration":"128.655248ms","start":"2026-06-09T17:20:35.527695Z","end":"2026-06-09T17:20:35.65635Z","steps":["trace[1442500728] 'read index received' (duration: 128.444925ms)","trace[1442500728] 'applied index is now lower than readState.Index' (duration: 209.443µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:20:35.656692Z","caller":"traceutil/trace.go:171","msg":"trace[999240992] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"149.789111ms","start":"2026-06-09T17:20:35.50689Z","end":"2026-06-09T17:20:35.656679Z","steps":["trace[999240992] 'process raft request' (duration: 149.324273ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:20:35.657065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.351572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-06-09T17:20:35.65711Z","caller":"traceutil/trace.go:171","msg":"trace[1554171049] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4981; }","duration":"129.438864ms","start":"2026-06-09T17:20:35.52766Z","end":"2026-06-09T17:20:35.657099Z","steps":["trace[1554171049] 'agreement among raft nodes before linearized reading' (duration: 129.21858ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:20:35.818886Z","caller":"traceutil/trace.go:171","msg":"trace[1724659448] transaction","detail":"{read_only:false; response_revision:4982; number_of_response:1; }","duration":"157.701311ms","start":"2026-06-09T17:20:35.661158Z","end":"2026-06-09T17:20:35.818859Z","steps":["trace[1724659448] 'process raft request' (duration: 157.508647ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:20:35.846625Z","caller":"traceutil/trace.go:171","msg":"trace[588826718] linearizableReadLoop","detail":"{readStateIndex:5177; appliedIndex:5175; }","duration":"127.301833ms","start":"2026-06-09T17:20:35.71929Z","end":"2026-06-09T17:20:35.846592Z","steps":["trace[588826718] 'read index received' (duration: 99.487593ms)","trace[588826718] 'applied index is now lower than readState.Index' (duration: 27.81305ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:20:35.846689Z","caller":"traceutil/trace.go:171","msg":"trace[274252907] transaction","detail":"{read_only:false; response_revision:4983; number_of_response:1; }","duration":"184.556413ms","start":"2026-06-09T17:20:35.662078Z","end":"2026-06-09T17:20:35.846635Z","steps":["trace[274252907] 'process raft request' (duration: 184.327078ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:20:35.847524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.201631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728695"} {"level":"info","ts":"2026-06-09T17:20:35.847593Z","caller":"traceutil/trace.go:171","msg":"trace[422001770] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4983; }","duration":"128.322973ms","start":"2026-06-09T17:20:35.719249Z","end":"2026-06-09T17:20:35.847572Z","steps":["trace[422001770] 'agreement among raft nodes before linearized reading' (duration: 127.549838ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:20:37.964041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.94946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-09T17:20:37.964137Z","caller":"traceutil/trace.go:171","msg":"trace[1265378730] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5021; }","duration":"102.086172ms","start":"2026-06-09T17:20:37.862026Z","end":"2026-06-09T17:20:37.964112Z","steps":["trace[1265378730] 'range keys from in-memory index tree' (duration: 101.802547ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:20:44.198134Z","caller":"traceutil/trace.go:171","msg":"trace[1289899035] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"111.249798ms","start":"2026-06-09T17:20:44.086848Z","end":"2026-06-09T17:20:44.198098Z","steps":["trace[1289899035] 'process raft request' (duration: 80.836989ms)","trace[1289899035] 'compare' (duration: 30.259156ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:20:44.314147Z","caller":"traceutil/trace.go:171","msg":"trace[1222314052] transaction","detail":"{read_only:false; response_revision:5164; number_of_response:1; }","duration":"103.628753ms","start":"2026-06-09T17:20:44.210498Z","end":"2026-06-09T17:20:44.314126Z","steps":["trace[1222314052] 'process raft request' (duration: 92.96452ms)","trace[1222314052] 'compare' (duration: 10.467049ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:20:44.314359Z","caller":"traceutil/trace.go:171","msg":"trace[518304258] transaction","detail":"{read_only:false; response_revision:5165; number_of_response:1; }","duration":"103.142624ms","start":"2026-06-09T17:20:44.211188Z","end":"2026-06-09T17:20:44.314331Z","steps":["trace[518304258] 'process raft request' (duration: 102.879639ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:20:48.941245Z","caller":"traceutil/trace.go:171","msg":"trace[901634692] linearizableReadLoop","detail":"{readStateIndex:5456; appliedIndex:5455; }","duration":"121.029804ms","start":"2026-06-09T17:20:48.820185Z","end":"2026-06-09T17:20:48.941215Z","steps":["trace[901634692] 'read index received' (duration: 120.903791ms)","trace[901634692] 'applied index is now lower than readState.Index' (duration: 124.753µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:20:48.941383Z","caller":"traceutil/trace.go:171","msg":"trace[2019439712] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"124.043032ms","start":"2026-06-09T17:20:48.817328Z","end":"2026-06-09T17:20:48.941371Z","steps":["trace[2019439712] 'process raft request' (duration: 123.706615ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:20:48.941578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.37636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:20:48.94165Z","caller":"traceutil/trace.go:171","msg":"trace[716518093] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:5254; }","duration":"121.505063ms","start":"2026-06-09T17:20:48.820134Z","end":"2026-06-09T17:20:48.941639Z","steps":["trace[716518093] 'agreement among raft nodes before linearized reading' (duration: 121.37504ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:20:48.94956Z","caller":"traceutil/trace.go:171","msg":"trace[768452129] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"105.153621ms","start":"2026-06-09T17:20:48.844382Z","end":"2026-06-09T17:20:48.949535Z","steps":["trace[768452129] 'process raft request' (duration: 105.011779ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:20:57.033791Z","caller":"traceutil/trace.go:171","msg":"trace[1000284863] transaction","detail":"{read_only:false; response_revision:5422; number_of_response:1; }","duration":"120.701588ms","start":"2026-06-09T17:20:56.913053Z","end":"2026-06-09T17:20:57.033755Z","steps":["trace[1000284863] 'process raft request' (duration: 120.421193ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:20:57.315944Z","caller":"traceutil/trace.go:171","msg":"trace[1636397229] transaction","detail":"{read_only:false; response_revision:5423; number_of_response:1; }","duration":"144.639841ms","start":"2026-06-09T17:20:57.171265Z","end":"2026-06-09T17:20:57.315904Z","steps":["trace[1636397229] 'process raft request' (duration: 59.43284ms)","trace[1636397229] 'compare' (duration: 84.771023ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:22:12.055659Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3001} {"level":"info","ts":"2026-06-09T17:22:12.140038Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3001,"took":"82.355938ms","hash":1823162984,"current-db-size-bytes":57970688,"current-db-size":"58 MB","current-db-size-in-use-bytes":32018432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-09T17:22:12.140105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1823162984,"revision":3001,"compact-revision":-1} {"level":"info","ts":"2026-06-09T17:26:50.534331Z","caller":"traceutil/trace.go:171","msg":"trace[1653955512] transaction","detail":"{read_only:false; response_revision:8180; number_of_response:1; }","duration":"359.442374ms","start":"2026-06-09T17:26:50.174854Z","end":"2026-06-09T17:26:50.534297Z","steps":["trace[1653955512] 'process raft request' (duration: 266.260382ms)","trace[1653955512] 'compare' (duration: 93.003409ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:26:50.53461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:26:50.174828Z","time spent":"359.691118ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:26:50.536314Z","caller":"traceutil/trace.go:171","msg":"trace[1829128481] linearizableReadLoop","detail":"{readStateIndex:8471; appliedIndex:8469; }","duration":"265.670132ms","start":"2026-06-09T17:26:50.270615Z","end":"2026-06-09T17:26:50.536285Z","steps":["trace[1829128481] 'read index received' (duration: 170.743417ms)","trace[1829128481] 'applied index is now lower than readState.Index' (duration: 94.925615ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:26:50.536593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.616296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:26:50.536702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.887564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-09T17:26:50.536712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.010778ms","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-06-09T17:26:50.536791Z","caller":"traceutil/trace.go:171","msg":"trace[448501028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8180; }","duration":"266.001298ms","start":"2026-06-09T17:26:50.270772Z","end":"2026-06-09T17:26:50.536773Z","steps":["trace[448501028] 'agreement among raft nodes before linearized reading' (duration: 265.731283ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:26:50.536827Z","caller":"traceutil/trace.go:171","msg":"trace[369486080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8180; }","duration":"266.220991ms","start":"2026-06-09T17:26:50.27058Z","end":"2026-06-09T17:26:50.536801Z","steps":["trace[369486080] 'agreement among raft nodes before linearized reading' (duration: 265.830034ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:26:50.536867Z","caller":"traceutil/trace.go:171","msg":"trace[1872290056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8180; }","duration":"167.83033ms","start":"2026-06-09T17:26:50.368923Z","end":"2026-06-09T17:26:50.536753Z","steps":["trace[1872290056] 'agreement among raft nodes before linearized reading' (duration: 167.596866ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:26:50.536705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.637914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-09T17:26:50.537036Z","caller":"traceutil/trace.go:171","msg":"trace[1508350672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8180; }","duration":"262.004031ms","start":"2026-06-09T17:26:50.275022Z","end":"2026-06-09T17:26:50.537026Z","steps":["trace[1508350672] 'agreement among raft nodes before linearized reading' (duration: 261.509521ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:27:01.874444Z","caller":"traceutil/trace.go:171","msg":"trace[815343272] transaction","detail":"{read_only:false; response_revision:8238; number_of_response:1; }","duration":"101.260273ms","start":"2026-06-09T17:27:01.773154Z","end":"2026-06-09T17:27:01.874414Z","steps":["trace[815343272] 'process raft request' (duration: 74.049487ms)","trace[815343272] 'compare' (duration: 27.004482ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:27:01.874674Z","caller":"traceutil/trace.go:171","msg":"trace[1398114924] transaction","detail":"{read_only:false; response_revision:8239; number_of_response:1; }","duration":"101.144651ms","start":"2026-06-09T17:27:01.773486Z","end":"2026-06-09T17:27:01.874631Z","steps":["trace[1398114924] 'process raft request' (duration: 100.882306ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:27:02.631832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.304777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:27:02.631922Z","caller":"traceutil/trace.go:171","msg":"trace[1403174336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8242; }","duration":"259.449779ms","start":"2026-06-09T17:27:02.37245Z","end":"2026-06-09T17:27:02.6319Z","steps":["trace[1403174336] 'range keys from in-memory index tree' (duration: 259.220584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:27:02.632049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.916663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-09T17:27:02.63231Z","caller":"traceutil/trace.go:171","msg":"trace[717389970] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8242; }","duration":"116.219768ms","start":"2026-06-09T17:27:02.516076Z","end":"2026-06-09T17:27:02.632296Z","steps":["trace[717389970] 'range keys from in-memory index tree' (duration: 115.719988ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:27:04.04142Z","caller":"traceutil/trace.go:171","msg":"trace[488406820] transaction","detail":"{read_only:false; response_revision:8249; number_of_response:1; }","duration":"161.412337ms","start":"2026-06-09T17:27:03.879978Z","end":"2026-06-09T17:27:04.04139Z","steps":["trace[488406820] 'process raft request' (duration: 161.10035ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:27:04.059243Z","caller":"traceutil/trace.go:171","msg":"trace[233393706] transaction","detail":"{read_only:false; response_revision:8250; number_of_response:1; }","duration":"179.095362ms","start":"2026-06-09T17:27:03.880114Z","end":"2026-06-09T17:27:04.059209Z","steps":["trace[233393706] 'process raft request' (duration: 178.872108ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:27:12.062825Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6122} {"level":"info","ts":"2026-06-09T17:27:12.16417Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6122,"took":"98.620783ms","hash":2625111005,"current-db-size-bytes":57970688,"current-db-size":"58 MB","current-db-size-in-use-bytes":23187456,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-06-09T17:27:12.164254Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2625111005,"revision":6122,"compact-revision":3001} {"level":"info","ts":"2026-06-09T17:27:30.531253Z","caller":"traceutil/trace.go:171","msg":"trace[1977687783] transaction","detail":"{read_only:false; response_revision:8444; number_of_response:1; }","duration":"391.09034ms","start":"2026-06-09T17:27:30.140135Z","end":"2026-06-09T17:27:30.531225Z","steps":["trace[1977687783] 'process raft request' (duration: 390.931627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:27:30.531424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:27:30.140113Z","time spent":"391.222233ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:27:30.533948Z","caller":"traceutil/trace.go:171","msg":"trace[1264685605] transaction","detail":"{read_only:false; response_revision:8445; number_of_response:1; }","duration":"384.800101ms","start":"2026-06-09T17:27:30.149123Z","end":"2026-06-09T17:27:30.533923Z","steps":["trace[1264685605] 'process raft request' (duration: 384.294672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:27:30.534089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:27:30.149091Z","time spent":"384.947493ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:27:30.535393Z","caller":"traceutil/trace.go:171","msg":"trace[2130782560] linearizableReadLoop","detail":"{readStateIndex:8747; appliedIndex:8745; }","duration":"360.827576ms","start":"2026-06-09T17:27:30.17455Z","end":"2026-06-09T17:27:30.535378Z","steps":["trace[2130782560] 'read index received' (duration: 356.549674ms)","trace[2130782560] 'applied index is now lower than readState.Index' (duration: 4.276692ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:27:30.535722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.174852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-09T17:27:30.53576Z","caller":"traceutil/trace.go:171","msg":"trace[1482257269] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8445; }","duration":"361.247993ms","start":"2026-06-09T17:27:30.174502Z","end":"2026-06-09T17:27:30.53575Z","steps":["trace[1482257269] 'agreement among raft nodes before linearized reading' (duration: 361.106651ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:27:30.535796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:27:30.174486Z","time spent":"361.298514ms","remote":"127.0.0.1:44476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-09T17:27:30.536018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.612748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:27:30.536048Z","caller":"traceutil/trace.go:171","msg":"trace[246465800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8445; }","duration":"313.67175ms","start":"2026-06-09T17:27:30.222367Z","end":"2026-06-09T17:27:30.536039Z","steps":["trace[246465800] 'agreement among raft nodes before linearized reading' (duration: 313.620249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:27:30.536072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:27:30.222354Z","time spent":"313.71294ms","remote":"127.0.0.1:44322","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-06-09T17:27:30.53645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.767819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:27:30.536483Z","caller":"traceutil/trace.go:171","msg":"trace[909751817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8445; }","duration":"165.829631ms","start":"2026-06-09T17:27:30.370643Z","end":"2026-06-09T17:27:30.536473Z","steps":["trace[909751817] 'agreement among raft nodes before linearized reading' (duration: 165.78634ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:27:30.536787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.614466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-09T17:27:30.536823Z","caller":"traceutil/trace.go:171","msg":"trace[1777236735] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8445; }","duration":"245.700388ms","start":"2026-06-09T17:27:30.291114Z","end":"2026-06-09T17:27:30.536814Z","steps":["trace[1777236735] 'agreement among raft nodes before linearized reading' (duration: 245.602816ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:27:43.234384Z","caller":"traceutil/trace.go:171","msg":"trace[425848957] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"158.858327ms","start":"2026-06-09T17:27:43.075498Z","end":"2026-06-09T17:27:43.234357Z","steps":["trace[425848957] 'process raft request' (duration: 158.604223ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:27:43.234586Z","caller":"traceutil/trace.go:171","msg":"trace[668239204] linearizableReadLoop","detail":"{readStateIndex:8866; appliedIndex:8864; }","duration":"115.863382ms","start":"2026-06-09T17:27:43.118686Z","end":"2026-06-09T17:27:43.234549Z","steps":["trace[668239204] 'read index received' (duration: 115.400273ms)","trace[668239204] 'applied index is now lower than readState.Index' (duration: 461.849µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:27:43.234933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.186758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-06-09T17:27:43.235027Z","caller":"traceutil/trace.go:171","msg":"trace[1268367271] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:8558; }","duration":"116.370391ms","start":"2026-06-09T17:27:43.118636Z","end":"2026-06-09T17:27:43.235006Z","steps":["trace[1268367271] 'agreement among raft nodes before linearized reading' (duration: 116.033125ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:27:43.235333Z","caller":"traceutil/trace.go:171","msg":"trace[731735542] transaction","detail":"{read_only:false; response_revision:8558; number_of_response:1; }","duration":"155.74575ms","start":"2026-06-09T17:27:43.079551Z","end":"2026-06-09T17:27:43.235297Z","steps":["trace[731735542] 'process raft request' (duration: 154.74044ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:27:43.388516Z","caller":"traceutil/trace.go:171","msg":"trace[641014154] linearizableReadLoop","detail":"{readStateIndex:8867; appliedIndex:8866; }","duration":"153.812772ms","start":"2026-06-09T17:27:43.234675Z","end":"2026-06-09T17:27:43.388488Z","steps":["trace[641014154] 'read index received' (duration: 107.620185ms)","trace[641014154] 'applied index is now lower than readState.Index' (duration: 46.191357ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:27:43.388606Z","caller":"traceutil/trace.go:171","msg":"trace[220897367] transaction","detail":"{read_only:false; response_revision:8559; number_of_response:1; }","duration":"156.474803ms","start":"2026-06-09T17:27:43.23208Z","end":"2026-06-09T17:27:43.388554Z","steps":["trace[220897367] 'process raft request' (duration: 110.210704ms)","trace[220897367] 'compare' (duration: 46.056895ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:27:43.388743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.767286ms","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:7"} {"level":"info","ts":"2026-06-09T17:27:43.388786Z","caller":"traceutil/trace.go:171","msg":"trace[1028057807] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8559; }","duration":"198.885639ms","start":"2026-06-09T17:27:43.189888Z","end":"2026-06-09T17:27:43.388774Z","steps":["trace[1028057807] 'agreement among raft nodes before linearized reading' (duration: 198.770097ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:27:43.388811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.851648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-9lc5m\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-06-09T17:27:43.388906Z","caller":"traceutil/trace.go:171","msg":"trace[697278562] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-9lc5m; range_end:; response_count:1; response_revision:8559; }","duration":"157.95809ms","start":"2026-06-09T17:27:43.230927Z","end":"2026-06-09T17:27:43.388885Z","steps":["trace[697278562] 'agreement among raft nodes before linearized reading' (duration: 157.646024ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:27:43.388934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.535429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0bb87710-b0fe-4e1d-ad50-9da06d3a0399\" ","response":"range_response_count:1 size:1275"} {"level":"info","ts":"2026-06-09T17:27:43.388979Z","caller":"traceutil/trace.go:171","msg":"trace[1727042269] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0bb87710-b0fe-4e1d-ad50-9da06d3a0399; range_end:; response_count:1; response_revision:8559; }","duration":"150.58857ms","start":"2026-06-09T17:27:43.238374Z","end":"2026-06-09T17:27:43.388962Z","steps":["trace[1727042269] 'agreement among raft nodes before linearized reading' (duration: 150.434057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:27:43.388991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.682112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-9lc5m\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-06-09T17:27:43.38907Z","caller":"traceutil/trace.go:171","msg":"trace[167089223] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-9lc5m; range_end:; response_count:1; response_revision:8559; }","duration":"150.827756ms","start":"2026-06-09T17:27:43.238219Z","end":"2026-06-09T17:27:43.389047Z","steps":["trace[167089223] 'agreement among raft nodes before linearized reading' (duration: 150.59248ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:27:44.15713Z","caller":"traceutil/trace.go:171","msg":"trace[1019223307] transaction","detail":"{read_only:false; response_revision:8561; number_of_response:1; }","duration":"192.873635ms","start":"2026-06-09T17:27:43.964223Z","end":"2026-06-09T17:27:44.157097Z","steps":["trace[1019223307] 'process raft request' (duration: 192.673341ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:27:44.166978Z","caller":"traceutil/trace.go:171","msg":"trace[1953104163] transaction","detail":"{read_only:false; response_revision:8562; number_of_response:1; }","duration":"110.766624ms","start":"2026-06-09T17:27:44.056178Z","end":"2026-06-09T17:27:44.166944Z","steps":["trace[1953104163] 'process raft request' (duration: 110.501189ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:27:44.690339Z","caller":"traceutil/trace.go:171","msg":"trace[2029388461] transaction","detail":"{read_only:false; response_revision:8564; number_of_response:1; }","duration":"101.360837ms","start":"2026-06-09T17:27:44.588951Z","end":"2026-06-09T17:27:44.690312Z","steps":["trace[2029388461] 'process raft request' (duration: 101.038961ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:27:45.032944Z","caller":"traceutil/trace.go:171","msg":"trace[1825148478] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"122.920396ms","start":"2026-06-09T17:27:44.909985Z","end":"2026-06-09T17:27:45.032906Z","steps":["trace[1825148478] 'process raft request' (duration: 100.763645ms)","trace[1825148478] 'compare' (duration: 21.864904ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:27:45.033272Z","caller":"traceutil/trace.go:171","msg":"trace[2123758166] transaction","detail":"{read_only:false; response_revision:8569; number_of_response:1; }","duration":"121.106522ms","start":"2026-06-09T17:27:44.91213Z","end":"2026-06-09T17:27:45.033237Z","steps":["trace[2123758166] 'process raft request' (duration: 120.713965ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:27:45.033409Z","caller":"traceutil/trace.go:171","msg":"trace[909634032] linearizableReadLoop","detail":"{readStateIndex:8876; appliedIndex:8874; }","duration":"122.08487ms","start":"2026-06-09T17:27:44.911298Z","end":"2026-06-09T17:27:45.033383Z","steps":["trace[909634032] 'read index received' (duration: 48.490671ms)","trace[909634032] 'applied index is now lower than readState.Index' (duration: 73.592728ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:27:45.033584Z","caller":"traceutil/trace.go:171","msg":"trace[732416793] transaction","detail":"{read_only:false; response_revision:8570; number_of_response:1; }","duration":"117.822719ms","start":"2026-06-09T17:27:44.915739Z","end":"2026-06-09T17:27:45.033562Z","steps":["trace[732416793] 'process raft request' (duration: 117.35548ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:27:45.033636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.298393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-09T17:27:45.033673Z","caller":"traceutil/trace.go:171","msg":"trace[532361766] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8570; }","duration":"122.387005ms","start":"2026-06-09T17:27:44.911273Z","end":"2026-06-09T17:27:45.03366Z","steps":["trace[532361766] 'agreement among raft nodes before linearized reading' (duration: 122.207181ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:28:31.649233Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002486.wal"} {"level":"info","ts":"2026-06-09T17:28:53.485175Z","caller":"traceutil/trace.go:171","msg":"trace[1102304982] linearizableReadLoop","detail":"{readStateIndex:9536; appliedIndex:9535; }","duration":"113.995485ms","start":"2026-06-09T17:28:53.371143Z","end":"2026-06-09T17:28:53.485139Z","steps":["trace[1102304982] 'read index received' (duration: 108.48196ms)","trace[1102304982] 'applied index is now lower than readState.Index' (duration: 5.512165ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:28:53.485248Z","caller":"traceutil/trace.go:171","msg":"trace[264917815] transaction","detail":"{read_only:false; response_revision:9208; number_of_response:1; }","duration":"154.286431ms","start":"2026-06-09T17:28:53.330927Z","end":"2026-06-09T17:28:53.485213Z","steps":["trace[264917815] 'process raft request' (duration: 148.704445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:28:53.485418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.254561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:28:53.485667Z","caller":"traceutil/trace.go:171","msg":"trace[1258857711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9208; }","duration":"114.381473ms","start":"2026-06-09T17:28:53.371086Z","end":"2026-06-09T17:28:53.485467Z","steps":["trace[1258857711] 'agreement among raft nodes before linearized reading' (duration: 114.24854ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:29:41.902146Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9abf795dae4ebc2c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T17:29:41.913985Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-09T17:29:41.914081Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-09T17:32:12.0702Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8299} {"level":"info","ts":"2026-06-09T17:32:12.151427Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8299,"took":"77.844679ms","hash":3528560783,"current-db-size-bytes":57970688,"current-db-size":"58 MB","current-db-size-in-use-bytes":26931200,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-09T17:32:12.15152Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3528560783,"revision":8299,"compact-revision":6122} {"level":"info","ts":"2026-06-09T17:37:12.078473Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10999} {"level":"info","ts":"2026-06-09T17:37:12.165365Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10999,"took":"83.359435ms","hash":2331049487,"current-db-size-bytes":57970688,"current-db-size":"58 MB","current-db-size-in-use-bytes":27992064,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-09T17:37:12.165433Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2331049487,"revision":10999,"compact-revision":8299} {"level":"info","ts":"2026-06-09T17:38:24.614951Z","caller":"traceutil/trace.go:171","msg":"trace[2029915711] transaction","detail":"{read_only:false; response_revision:13534; number_of_response:1; }","duration":"141.427518ms","start":"2026-06-09T17:38:24.473494Z","end":"2026-06-09T17:38:24.614922Z","steps":["trace[2029915711] 'process raft request' (duration: 141.252054ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:39:28.802917Z","caller":"traceutil/trace.go:171","msg":"trace[747958490] linearizableReadLoop","detail":"{readStateIndex:14343; appliedIndex:14340; }","duration":"171.460389ms","start":"2026-06-09T17:39:28.631433Z","end":"2026-06-09T17:39:28.802893Z","steps":["trace[747958490] 'read index received' (duration: 70.749705ms)","trace[747958490] 'applied index is now lower than readState.Index' (duration: 100.709724ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:39:28.802983Z","caller":"traceutil/trace.go:171","msg":"trace[1345872253] transaction","detail":"{read_only:false; response_revision:13860; number_of_response:1; }","duration":"173.772683ms","start":"2026-06-09T17:39:28.62918Z","end":"2026-06-09T17:39:28.802953Z","steps":["trace[1345872253] 'process raft request' (duration: 173.155281ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:39:28.803037Z","caller":"traceutil/trace.go:171","msg":"trace[421745969] transaction","detail":"{read_only:false; response_revision:13861; number_of_response:1; }","duration":"173.130461ms","start":"2026-06-09T17:39:28.629871Z","end":"2026-06-09T17:39:28.803002Z","steps":["trace[421745969] 'process raft request' (duration: 172.969528ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:39:28.803114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.652162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:39:28.803164Z","caller":"traceutil/trace.go:171","msg":"trace[409967123] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:13861; }","duration":"171.747375ms","start":"2026-06-09T17:39:28.631403Z","end":"2026-06-09T17:39:28.80315Z","steps":["trace[409967123] 'agreement among raft nodes before linearized reading' (duration: 171.647513ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:39:28.803207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.117884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-09T17:39:28.803307Z","caller":"traceutil/trace.go:171","msg":"trace[1642188636] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13861; }","duration":"169.224586ms","start":"2026-06-09T17:39:28.634062Z","end":"2026-06-09T17:39:28.803286Z","steps":["trace[1642188636] 'agreement among raft nodes before linearized reading' (duration: 169.034933ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:39:28.803361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.624869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2bt6x\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-09T17:39:28.803413Z","caller":"traceutil/trace.go:171","msg":"trace[1685194752] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2bt6x; range_end:; response_count:1; response_revision:13861; }","duration":"103.715651ms","start":"2026-06-09T17:39:28.699685Z","end":"2026-06-09T17:39:28.8034Z","steps":["trace[1685194752] 'agreement among raft nodes before linearized reading' (duration: 103.571189ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:39:37.055878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.710335ms","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:5"} {"level":"info","ts":"2026-06-09T17:39:37.055987Z","caller":"traceutil/trace.go:171","msg":"trace[417630747] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:13917; }","duration":"301.878638ms","start":"2026-06-09T17:39:36.754088Z","end":"2026-06-09T17:39:37.055966Z","steps":["trace[417630747] 'count revisions from in-memory index tree' (duration: 301.598352ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:39:37.056028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:39:36.754066Z","time spent":"301.949829ms","remote":"127.0.0.1:54364","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-06-09T17:39:37.05602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.466535ms","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-06-09T17:39:37.056098Z","caller":"traceutil/trace.go:171","msg":"trace[214671721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13917; }","duration":"326.573346ms","start":"2026-06-09T17:39:36.7295Z","end":"2026-06-09T17:39:37.056074Z","steps":["trace[214671721] 'range keys from in-memory index tree' (duration: 326.262701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:39:37.055918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.908831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:39:37.056241Z","caller":"traceutil/trace.go:171","msg":"trace[1402182355] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:13917; }","duration":"340.284257ms","start":"2026-06-09T17:39:36.715936Z","end":"2026-06-09T17:39:37.056221Z","steps":["trace[1402182355] 'count revisions from in-memory index tree' (duration: 339.821068ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:39:37.0563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:39:36.715914Z","time spent":"340.368519ms","remote":"127.0.0.1:44652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:39:37.056298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.277413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-09T17:39:37.056134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:39:36.729489Z","time spent":"326.636787ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-09T17:39:37.056346Z","caller":"traceutil/trace.go:171","msg":"trace[1601047622] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13917; }","duration":"213.328764ms","start":"2026-06-09T17:39:36.843005Z","end":"2026-06-09T17:39:37.056333Z","steps":["trace[1601047622] 'range keys from in-memory index tree' (duration: 213.175151ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:39:37.056364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.475425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-09T17:39:37.056366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.449563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-09T17:39:37.056407Z","caller":"traceutil/trace.go:171","msg":"trace[2073374487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13917; }","duration":"215.523106ms","start":"2026-06-09T17:39:36.840873Z","end":"2026-06-09T17:39:37.056397Z","steps":["trace[2073374487] 'range keys from in-memory index tree' (duration: 215.348413ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:39:37.056449Z","caller":"traceutil/trace.go:171","msg":"trace[1409388697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13917; }","duration":"216.567426ms","start":"2026-06-09T17:39:36.839856Z","end":"2026-06-09T17:39:37.056423Z","steps":["trace[1409388697] 'range keys from in-memory index tree' (duration: 216.28918ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:39:37.05965Z","caller":"traceutil/trace.go:171","msg":"trace[74441614] linearizableReadLoop","detail":"{readStateIndex:14404; appliedIndex:14403; }","duration":"195.502076ms","start":"2026-06-09T17:39:36.864122Z","end":"2026-06-09T17:39:37.059624Z","steps":["trace[74441614] 'read index received' (duration: 195.25633ms)","trace[74441614] 'applied index is now lower than readState.Index' (duration: 245.046µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:39:37.05986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.729191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2bt6x\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-09T17:39:37.059903Z","caller":"traceutil/trace.go:171","msg":"trace[2015417183] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2bt6x; range_end:; response_count:1; response_revision:13918; }","duration":"195.826052ms","start":"2026-06-09T17:39:36.864062Z","end":"2026-06-09T17:39:37.059888Z","steps":["trace[2015417183] 'agreement among raft nodes before linearized reading' (duration: 195.665859ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:39:37.059873Z","caller":"traceutil/trace.go:171","msg":"trace[1491651415] transaction","detail":"{read_only:false; response_revision:13918; number_of_response:1; }","duration":"205.065088ms","start":"2026-06-09T17:39:36.854782Z","end":"2026-06-09T17:39:37.059847Z","steps":["trace[1491651415] 'process raft request' (duration: 204.690311ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:12.084396Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13028} {"level":"info","ts":"2026-06-09T17:42:12.153082Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13028,"took":"66.500492ms","hash":1250709035,"current-db-size-bytes":57970688,"current-db-size":"58 MB","current-db-size-in-use-bytes":29765632,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-09T17:42:12.153136Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1250709035,"revision":13028,"compact-revision":10999} {"level":"warn","ts":"2026-06-09T17:46:33.664357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.27525ms","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-06-09T17:46:33.664432Z","caller":"traceutil/trace.go:171","msg":"trace[1719882844] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:17411; }","duration":"136.394142ms","start":"2026-06-09T17:46:33.528022Z","end":"2026-06-09T17:46:33.664416Z","steps":["trace[1719882844] 'range keys from in-memory index tree' (duration: 136.142738ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:46:38.822745Z","caller":"traceutil/trace.go:171","msg":"trace[197182392] linearizableReadLoop","detail":"{readStateIndex:18046; appliedIndex:18045; }","duration":"126.945652ms","start":"2026-06-09T17:46:38.695771Z","end":"2026-06-09T17:46:38.822716Z","steps":["trace[197182392] 'read index received' (duration: 126.633436ms)","trace[197182392] 'applied index is now lower than readState.Index' (duration: 310.986µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:46:38.823068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.186938ms","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-06-09T17:46:38.823181Z","caller":"traceutil/trace.go:171","msg":"trace[150892135] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:17447; }","duration":"127.454543ms","start":"2026-06-09T17:46:38.695703Z","end":"2026-06-09T17:46:38.823157Z","steps":["trace[150892135] 'agreement among raft nodes before linearized reading' (duration: 127.169737ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:46:38.823453Z","caller":"traceutil/trace.go:171","msg":"trace[1943875627] transaction","detail":"{read_only:false; response_revision:17447; number_of_response:1; }","duration":"222.834395ms","start":"2026-06-09T17:46:38.600001Z","end":"2026-06-09T17:46:38.822836Z","steps":["trace[1943875627] 'process raft request' (duration: 222.464578ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:46:39.108948Z","caller":"traceutil/trace.go:171","msg":"trace[1722230521] transaction","detail":"{read_only:false; response_revision:17448; number_of_response:1; }","duration":"246.677028ms","start":"2026-06-09T17:46:38.862238Z","end":"2026-06-09T17:46:39.108915Z","steps":["trace[1722230521] 'process raft request' (duration: 236.77145ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:46:39.204731Z","caller":"traceutil/trace.go:171","msg":"trace[183186953] transaction","detail":"{read_only:false; response_revision:17449; number_of_response:1; }","duration":"340.9686ms","start":"2026-06-09T17:46:38.863707Z","end":"2026-06-09T17:46:39.204676Z","steps":["trace[183186953] 'process raft request' (duration: 340.654394ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:46:39.204988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:46:38.863659Z","time spent":"341.196324ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:46:39.205109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.532372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-09T17:46:39.205207Z","caller":"traceutil/trace.go:171","msg":"trace[3911385] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17449; }","duration":"239.702046ms","start":"2026-06-09T17:46:38.965483Z","end":"2026-06-09T17:46:39.205185Z","steps":["trace[3911385] 'agreement among raft nodes before linearized reading' (duration: 239.42667ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:46:39.204565Z","caller":"traceutil/trace.go:171","msg":"trace[1224826751] linearizableReadLoop","detail":"{readStateIndex:18048; appliedIndex:18046; }","duration":"238.994053ms","start":"2026-06-09T17:46:38.96554Z","end":"2026-06-09T17:46:39.204534Z","steps":["trace[1224826751] 'read index received' (duration: 133.488448ms)","trace[1224826751] 'applied index is now lower than readState.Index' (duration: 105.500834ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:47:12.104935Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15348} {"level":"info","ts":"2026-06-09T17:47:12.200996Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15348,"took":"93.305134ms","hash":2639144984,"current-db-size-bytes":57970688,"current-db-size":"58 MB","current-db-size-in-use-bytes":32436224,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-09T17:47:12.201072Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2639144984,"revision":15348,"compact-revision":13028} {"level":"info","ts":"2026-06-09T17:50:23.63991Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9abf795dae4ebc2c","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T17:50:23.644519Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-09T17:50:23.644584Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-09T17:52:08.335785Z","caller":"traceutil/trace.go:171","msg":"trace[1558678233] linearizableReadLoop","detail":"{readStateIndex:20726; appliedIndex:20725; }","duration":"204.409278ms","start":"2026-06-09T17:52:08.131346Z","end":"2026-06-09T17:52:08.335756Z","steps":["trace[1558678233] 'read index received' (duration: 204.051812ms)","trace[1558678233] 'applied index is now lower than readState.Index' (duration: 355.986µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:52:08.335881Z","caller":"traceutil/trace.go:171","msg":"trace[568000168] transaction","detail":"{read_only:false; response_revision:20040; number_of_response:1; }","duration":"261.170267ms","start":"2026-06-09T17:52:08.074675Z","end":"2026-06-09T17:52:08.335845Z","steps":["trace[568000168] 'process raft request' (duration: 260.833001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:52:08.336082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.718345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-09T17:52:08.336128Z","caller":"traceutil/trace.go:171","msg":"trace[1044803557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20040; }","duration":"204.817637ms","start":"2026-06-09T17:52:08.1313Z","end":"2026-06-09T17:52:08.336118Z","steps":["trace[1044803557] 'agreement among raft nodes before linearized reading' (duration: 204.580032ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:52:08.644339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.914504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:52:08.644896Z","caller":"traceutil/trace.go:171","msg":"trace[1661108516] transaction","detail":"{read_only:false; response_revision:20041; number_of_response:1; }","duration":"304.241477ms","start":"2026-06-09T17:52:08.340568Z","end":"2026-06-09T17:52:08.644809Z","steps":["trace[1661108516] 'process raft request' (duration: 33.678261ms)","trace[1661108516] 'compare' (duration: 269.352143ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:52:08.64499Z","caller":"traceutil/trace.go:171","msg":"trace[2022405115] linearizableReadLoop","detail":"{readStateIndex:20727; appliedIndex:20726; }","duration":"273.751697ms","start":"2026-06-09T17:52:08.371176Z","end":"2026-06-09T17:52:08.644928Z","steps":["trace[2022405115] 'read index received' (duration: 2.961156ms)","trace[2022405115] 'applied index is now lower than readState.Index' (duration: 270.7859ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:52:08.645058Z","caller":"traceutil/trace.go:171","msg":"trace[888235017] transaction","detail":"{read_only:false; response_revision:20042; number_of_response:1; }","duration":"218.930695ms","start":"2026-06-09T17:52:08.426107Z","end":"2026-06-09T17:52:08.645038Z","steps":["trace[888235017] 'process raft request' (duration: 218.747881ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:52:08.645137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:52:08.34054Z","time spent":"304.476821ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:52:08.645176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.983991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:52:08.645277Z","caller":"traceutil/trace.go:171","msg":"trace[421891739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20042; }","duration":"274.126794ms","start":"2026-06-09T17:52:08.371128Z","end":"2026-06-09T17:52:08.645255Z","steps":["trace[421891739] 'agreement among raft nodes before linearized reading' (duration: 273.889279ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:52:08.896169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.182936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:52:08.896323Z","caller":"traceutil/trace.go:171","msg":"trace[225965683] linearizableReadLoop","detail":"{readStateIndex:20729; appliedIndex:20728; }","duration":"249.895053ms","start":"2026-06-09T17:52:08.646407Z","end":"2026-06-09T17:52:08.896302Z","steps":["trace[225965683] 'read index received' (duration: 118.384092ms)","trace[225965683] 'applied index is now lower than readState.Index' (duration: 131.509391ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:52:08.896354Z","caller":"traceutil/trace.go:171","msg":"trace[1139412791] transaction","detail":"{read_only:false; response_revision:20043; number_of_response:1; }","duration":"457.266679ms","start":"2026-06-09T17:52:08.439045Z","end":"2026-06-09T17:52:08.896312Z","steps":["trace[1139412791] 'process raft request' (duration: 325.832438ms)","trace[1139412791] 'compare' (duration: 131.015362ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:52:08.896436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.031907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:52:08.896484Z","caller":"traceutil/trace.go:171","msg":"trace[2034216970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20043; }","duration":"250.105827ms","start":"2026-06-09T17:52:08.646351Z","end":"2026-06-09T17:52:08.896457Z","steps":["trace[2034216970] 'agreement among raft nodes before linearized reading' (duration: 250.045376ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:52:08.896515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:52:08.439014Z","time spent":"457.414891ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:52:09.306788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.307999ms","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-06-09T17:52:09.306867Z","caller":"traceutil/trace.go:171","msg":"trace[83365103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20043; }","duration":"283.454972ms","start":"2026-06-09T17:52:09.023395Z","end":"2026-06-09T17:52:09.30685Z","steps":["trace[83365103] 'range keys from in-memory index tree' (duration: 283.078054ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:52:09.307203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.318904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-06-09T17:52:09.307294Z","caller":"traceutil/trace.go:171","msg":"trace[27892729] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:20043; }","duration":"104.461636ms","start":"2026-06-09T17:52:09.202806Z","end":"2026-06-09T17:52:09.307268Z","steps":["trace[27892729] 'range keys from in-memory index tree' (duration: 104.137891ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:52:09.412887Z","caller":"traceutil/trace.go:171","msg":"trace[1532643929] transaction","detail":"{read_only:false; response_revision:20044; number_of_response:1; }","duration":"101.134144ms","start":"2026-06-09T17:52:09.311716Z","end":"2026-06-09T17:52:09.41285Z","steps":["trace[1532643929] 'process raft request' (duration: 100.882019ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:52:09.436444Z","caller":"traceutil/trace.go:171","msg":"trace[252788714] transaction","detail":"{read_only:false; response_revision:20045; number_of_response:1; }","duration":"123.432578ms","start":"2026-06-09T17:52:09.312964Z","end":"2026-06-09T17:52:09.436396Z","steps":["trace[252788714] 'process raft request' (duration: 123.133923ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:52:09.436469Z","caller":"traceutil/trace.go:171","msg":"trace[1350482639] transaction","detail":"{read_only:false; response_revision:20046; number_of_response:1; }","duration":"123.422789ms","start":"2026-06-09T17:52:09.313004Z","end":"2026-06-09T17:52:09.436427Z","steps":["trace[1350482639] 'process raft request' (duration: 123.245095ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:52:09.436566Z","caller":"traceutil/trace.go:171","msg":"trace[2100674030] transaction","detail":"{read_only:false; response_revision:20048; number_of_response:1; }","duration":"122.44931ms","start":"2026-06-09T17:52:09.314106Z","end":"2026-06-09T17:52:09.436555Z","steps":["trace[2100674030] 'process raft request' (duration: 122.237785ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:52:09.43646Z","caller":"traceutil/trace.go:171","msg":"trace[500083937] transaction","detail":"{read_only:false; response_revision:20047; number_of_response:1; }","duration":"123.297386ms","start":"2026-06-09T17:52:09.313135Z","end":"2026-06-09T17:52:09.436432Z","steps":["trace[500083937] 'process raft request' (duration: 123.162412ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:52:12.112993Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17789} {"level":"info","ts":"2026-06-09T17:52:12.226443Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17789,"took":"109.199327ms","hash":2061347440,"current-db-size-bytes":57970688,"current-db-size":"58 MB","current-db-size-in-use-bytes":29749248,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-09T17:52:12.226538Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2061347440,"revision":17789,"compact-revision":15348} {"level":"info","ts":"2026-06-09T17:55:56.604681Z","caller":"traceutil/trace.go:171","msg":"trace[775998056] transaction","detail":"{read_only:false; response_revision:22427; number_of_response:1; }","duration":"102.868057ms","start":"2026-06-09T17:55:56.501794Z","end":"2026-06-09T17:55:56.604663Z","steps":["trace[775998056] 'process raft request' (duration: 102.700164ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:55:56.93549Z","caller":"traceutil/trace.go:171","msg":"trace[1830169121] linearizableReadLoop","detail":"{readStateIndex:23187; appliedIndex:23186; }","duration":"300.043077ms","start":"2026-06-09T17:55:56.635418Z","end":"2026-06-09T17:55:56.935461Z","steps":["trace[1830169121] 'read index received' (duration: 268.507757ms)","trace[1830169121] 'applied index is now lower than readState.Index' (duration: 31.53419ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:55:56.935603Z","caller":"traceutil/trace.go:171","msg":"trace[297190486] transaction","detail":"{read_only:false; response_revision:22428; number_of_response:1; }","duration":"305.161495ms","start":"2026-06-09T17:55:56.630327Z","end":"2026-06-09T17:55:56.935488Z","steps":["trace[297190486] 'process raft request' (duration: 273.442683ms)","trace[297190486] 'compare' (duration: 31.562489ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:55:56.935787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:55:56.63029Z","time spent":"305.40388ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:55:56.935795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.168221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-06-09T17:55:56.935829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.563688ms","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-06-09T17:55:56.935858Z","caller":"traceutil/trace.go:171","msg":"trace[806137863] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:22428; }","duration":"269.268523ms","start":"2026-06-09T17:55:56.666568Z","end":"2026-06-09T17:55:56.935836Z","steps":["trace[806137863] 'agreement among raft nodes before linearized reading' (duration: 269.086688ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:55:56.935895Z","caller":"traceutil/trace.go:171","msg":"trace[284180216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22428; }","duration":"289.690721ms","start":"2026-06-09T17:55:56.64619Z","end":"2026-06-09T17:55:56.935881Z","steps":["trace[284180216] 'agreement among raft nodes before linearized reading' (duration: 289.466545ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:55:56.935841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.402264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-06-09T17:55:56.935957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.835476ms","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-06-09T17:55:56.936074Z","caller":"traceutil/trace.go:171","msg":"trace[1080613222] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22428; }","duration":"300.685639ms","start":"2026-06-09T17:55:56.635354Z","end":"2026-06-09T17:55:56.93604Z","steps":["trace[1080613222] 'agreement among raft nodes before linearized reading' (duration: 300.24969ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:55:56.936078Z","caller":"traceutil/trace.go:171","msg":"trace[1029243434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22428; }","duration":"288.008459ms","start":"2026-06-09T17:55:56.64804Z","end":"2026-06-09T17:55:56.936048Z","steps":["trace[1029243434] 'agreement among raft nodes before linearized reading' (duration: 287.624011ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:55:56.936137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:55:56.635313Z","time spent":"300.809632ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-09T17:55:57.043413Z","caller":"traceutil/trace.go:171","msg":"trace[1966185625] transaction","detail":"{read_only:false; response_revision:22430; number_of_response:1; }","duration":"102.469089ms","start":"2026-06-09T17:55:56.940924Z","end":"2026-06-09T17:55:57.043393Z","steps":["trace[1966185625] 'process raft request' (duration: 102.311136ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:55:57.043841Z","caller":"traceutil/trace.go:171","msg":"trace[1848856282] transaction","detail":"{read_only:false; response_revision:22431; number_of_response:1; }","duration":"102.616103ms","start":"2026-06-09T17:55:56.941185Z","end":"2026-06-09T17:55:57.043801Z","steps":["trace[1848856282] 'process raft request' (duration: 102.172114ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:50.565253Z","caller":"traceutil/trace.go:171","msg":"trace[1156627482] transaction","detail":"{read_only:false; response_revision:22949; number_of_response:1; }","duration":"140.201716ms","start":"2026-06-09T17:56:50.425011Z","end":"2026-06-09T17:56:50.565213Z","steps":["trace[1156627482] 'process raft request' (duration: 139.997943ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:12.122891Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20058} {"level":"info","ts":"2026-06-09T17:57:12.232825Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20058,"took":"106.316101ms","hash":1130461497,"current-db-size-bytes":58822656,"current-db-size":"59 MB","current-db-size-in-use-bytes":40935424,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-06-09T17:57:12.232887Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1130461497,"revision":20058,"compact-revision":17789} {"level":"warn","ts":"2026-06-09T17:59:30.327318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.806903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:59:30.327598Z","caller":"traceutil/trace.go:171","msg":"trace[814248468] transaction","detail":"{read_only:false; response_revision:24556; number_of_response:1; }","duration":"173.222403ms","start":"2026-06-09T17:59:30.154353Z","end":"2026-06-09T17:59:30.327576Z","steps":["trace[814248468] 'process raft request' (duration: 173.177672ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:30.32761Z","caller":"traceutil/trace.go:171","msg":"trace[1953038305] transaction","detail":"{read_only:false; response_revision:24555; number_of_response:1; }","duration":"280.887851ms","start":"2026-06-09T17:59:30.046709Z","end":"2026-06-09T17:59:30.327596Z","steps":["trace[1953038305] 'process raft request' (duration: 280.735738ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:30.327841Z","caller":"traceutil/trace.go:171","msg":"trace[1091128079] linearizableReadLoop","detail":"{readStateIndex:25371; appliedIndex:25369; }","duration":"255.391755ms","start":"2026-06-09T17:59:30.072438Z","end":"2026-06-09T17:59:30.327829Z","steps":["trace[1091128079] 'read index received' (duration: 110.659584ms)","trace[1091128079] 'applied index is now lower than readState.Index' (duration: 144.731101ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:30.328047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.599609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:59:30.328091Z","caller":"traceutil/trace.go:171","msg":"trace[796117907] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:24556; }","duration":"255.67014ms","start":"2026-06-09T17:59:30.072408Z","end":"2026-06-09T17:59:30.328079Z","steps":["trace[796117907] 'agreement among raft nodes before linearized reading' (duration: 255.594909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:30.328494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.089971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:59:30.328577Z","caller":"traceutil/trace.go:171","msg":"trace[1414439233] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:24556; }","duration":"223.254504ms","start":"2026-06-09T17:59:30.105303Z","end":"2026-06-09T17:59:30.328557Z","steps":["trace[1414439233] 'agreement among raft nodes before linearized reading' (duration: 223.04732ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:30.328625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.879662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-06-09T17:59:30.32869Z","caller":"traceutil/trace.go:171","msg":"trace[1724518575] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:24556; }","duration":"157.963633ms","start":"2026-06-09T17:59:30.170715Z","end":"2026-06-09T17:59:30.328679Z","steps":["trace[1724518575] 'agreement among raft nodes before linearized reading' (duration: 157.786489ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:30.328697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.811239ms","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-06-09T17:59:30.328762Z","caller":"traceutil/trace.go:171","msg":"trace[2013044878] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24556; }","duration":"168.898092ms","start":"2026-06-09T17:59:30.159846Z","end":"2026-06-09T17:59:30.328745Z","steps":["trace[2013044878] 'agreement among raft nodes before linearized reading' (duration: 168.719017ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:30.328877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.074121ms","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-06-09T17:59:30.328911Z","caller":"traceutil/trace.go:171","msg":"trace[1806949745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24556; }","duration":"173.129211ms","start":"2026-06-09T17:59:30.155772Z","end":"2026-06-09T17:59:30.328902Z","steps":["trace[1806949745] 'agreement among raft nodes before linearized reading' (duration: 172.978089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:30.329012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.724387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:59:30.329028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.663578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-09T17:59:30.329052Z","caller":"traceutil/trace.go:171","msg":"trace[2077530040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24556; }","duration":"108.795459ms","start":"2026-06-09T17:59:30.220247Z","end":"2026-06-09T17:59:30.329042Z","steps":["trace[2077530040] 'agreement among raft nodes before linearized reading' (duration: 108.738898ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:30.329062Z","caller":"traceutil/trace.go:171","msg":"trace[1399217017] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24556; }","duration":"167.713189ms","start":"2026-06-09T17:59:30.161338Z","end":"2026-06-09T17:59:30.329051Z","steps":["trace[1399217017] 'agreement among raft nodes before linearized reading' (duration: 167.615897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:30.329087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.315378ms","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-06-09T17:59:30.329197Z","caller":"traceutil/trace.go:171","msg":"trace[789312382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24556; }","duration":"180.42231ms","start":"2026-06-09T17:59:30.148765Z","end":"2026-06-09T17:59:30.329187Z","steps":["trace[789312382] 'agreement among raft nodes before linearized reading' (duration: 180.269977ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:30.327562Z","caller":"traceutil/trace.go:171","msg":"trace[809906301] transaction","detail":"{read_only:false; response_revision:24554; number_of_response:1; }","duration":"306.599719ms","start":"2026-06-09T17:59:30.020899Z","end":"2026-06-09T17:59:30.327499Z","steps":["trace[809906301] 'process raft request' (duration: 162.197824ms)","trace[809906301] 'compare' (duration: 143.341924ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:30.331348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:30.020892Z","time spent":"309.628586ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:59:30.797424Z","caller":"traceutil/trace.go:171","msg":"trace[1229485337] linearizableReadLoop","detail":"{readStateIndex:25373; appliedIndex:25372; }","duration":"460.552236ms","start":"2026-06-09T17:59:30.336855Z","end":"2026-06-09T17:59:30.797408Z","steps":["trace[1229485337] 'read index received' (duration: 460.543775ms)","trace[1229485337] 'applied index is now lower than readState.Index' (duration: 7.511µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:59:30.797436Z","caller":"traceutil/trace.go:171","msg":"trace[1446900285] transaction","detail":"{read_only:false; response_revision:24557; number_of_response:1; }","duration":"463.607994ms","start":"2026-06-09T17:59:30.333808Z","end":"2026-06-09T17:59:30.797416Z","steps":["trace[1446900285] 'process raft request' (duration: 463.455051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:30.797562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:30.333785Z","time spent":"463.705535ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:59:30.797836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.920863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0bb87710-b0fe-4e1d-ad50-9da06d3a0399\" ","response":"range_response_count:1 size:1276"} {"level":"info","ts":"2026-06-09T17:59:30.797966Z","caller":"traceutil/trace.go:171","msg":"trace[1171103828] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0bb87710-b0fe-4e1d-ad50-9da06d3a0399; range_end:; response_count:1; response_revision:24557; }","duration":"461.105067ms","start":"2026-06-09T17:59:30.336827Z","end":"2026-06-09T17:59:30.797932Z","steps":["trace[1171103828] 'agreement among raft nodes before linearized reading' (duration: 460.676218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:30.798032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:30.336816Z","time spent":"461.205249ms","remote":"127.0.0.1:44446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1300,"request content":"key:\"/registry/persistentvolumes/pvc-0bb87710-b0fe-4e1d-ad50-9da06d3a0399\" "} {"level":"info","ts":"2026-06-09T17:59:31.178885Z","caller":"traceutil/trace.go:171","msg":"trace[1268882456] transaction","detail":"{read_only:false; response_revision:24560; number_of_response:1; }","duration":"841.08683ms","start":"2026-06-09T17:59:30.337766Z","end":"2026-06-09T17:59:31.178853Z","steps":["trace[1268882456] 'process raft request' (duration: 840.647161ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:31.178929Z","caller":"traceutil/trace.go:171","msg":"trace[1812455805] transaction","detail":"{read_only:false; response_revision:24561; number_of_response:1; }","duration":"840.923818ms","start":"2026-06-09T17:59:30.337966Z","end":"2026-06-09T17:59:31.178889Z","steps":["trace[1812455805] 'process raft request' (duration: 840.482358ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:31.178991Z","caller":"traceutil/trace.go:171","msg":"trace[806602020] transaction","detail":"{read_only:false; response_revision:24558; number_of_response:1; }","duration":"841.946576ms","start":"2026-06-09T17:59:30.337005Z","end":"2026-06-09T17:59:31.178951Z","steps":["trace[806602020] 'process raft request' (duration: 841.15875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:31.17906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:30.337756Z","time spent":"841.210373ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:59:31.179218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:30.337959Z","time spent":"841.0907ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:59:31.179178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:30.336984Z","time spent":"842.09291ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:59:31.179861Z","caller":"traceutil/trace.go:171","msg":"trace[1856745775] transaction","detail":"{read_only:false; response_revision:24559; number_of_response:1; }","duration":"841.790893ms","start":"2026-06-09T17:59:30.337018Z","end":"2026-06-09T17:59:31.178809Z","steps":["trace[1856745775] 'process raft request' (duration: 841.346174ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:31.179983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:30.337004Z","time spent":"842.926755ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:59:31.227173Z","caller":"traceutil/trace.go:171","msg":"trace[1609708614] transaction","detail":"{read_only:false; response_revision:24562; number_of_response:1; }","duration":"501.103927ms","start":"2026-06-09T17:59:30.725838Z","end":"2026-06-09T17:59:31.226942Z","steps":["trace[1609708614] 'process raft request' (duration: 500.668869ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:31.227272Z","caller":"traceutil/trace.go:171","msg":"trace[1923841460] linearizableReadLoop","detail":"{readStateIndex:25381; appliedIndex:25373; }","duration":"429.76947ms","start":"2026-06-09T17:59:30.797476Z","end":"2026-06-09T17:59:31.227245Z","steps":["trace[1923841460] 'read index received' (duration: 380.580195ms)","trace[1923841460] 'applied index is now lower than readState.Index' (duration: 49.188165ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:31.227375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:30.725816Z","time spent":"501.454713ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:59:31.227376Z","caller":"traceutil/trace.go:171","msg":"trace[938677808] transaction","detail":"{read_only:false; response_revision:24563; number_of_response:1; }","duration":"431.663066ms","start":"2026-06-09T17:59:30.795652Z","end":"2026-06-09T17:59:31.227315Z","steps":["trace[938677808] 'process raft request' (duration: 431.210258ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:31.22752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:30.79562Z","time spent":"431.823049ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:59:31.227595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.519267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7914"} {"level":"info","ts":"2026-06-09T17:59:31.227407Z","caller":"traceutil/trace.go:171","msg":"trace[759380988] transaction","detail":"{read_only:false; response_revision:24564; number_of_response:1; }","duration":"431.214698ms","start":"2026-06-09T17:59:30.79618Z","end":"2026-06-09T17:59:31.227394Z","steps":["trace[759380988] 'process raft request' (duration: 431.012434ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:31.227641Z","caller":"traceutil/trace.go:171","msg":"trace[112434377] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:24564; }","duration":"833.626419ms","start":"2026-06-09T17:59:30.394003Z","end":"2026-06-09T17:59:31.227629Z","steps":["trace[112434377] 'agreement among raft nodes before linearized reading' (duration: 833.428964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:31.227666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:30.796168Z","time spent":"431.472893ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:59:31.227685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:30.393982Z","time spent":"833.69428ms","remote":"127.0.0.1:32782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7938,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-06-09T17:59:31.498013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.311198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:59:31.498138Z","caller":"traceutil/trace.go:171","msg":"trace[1943311233] linearizableReadLoop","detail":"{readStateIndex:25382; appliedIndex:25381; }","duration":"270.768288ms","start":"2026-06-09T17:59:31.22735Z","end":"2026-06-09T17:59:31.498119Z","steps":["trace[1943311233] 'read index received' (duration: 100.157225ms)","trace[1943311233] 'applied index is now lower than readState.Index' (duration: 170.609633ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:59:31.498306Z","caller":"traceutil/trace.go:171","msg":"trace[928444522] transaction","detail":"{read_only:false; response_revision:24565; number_of_response:1; }","duration":"290.660396ms","start":"2026-06-09T17:59:31.207616Z","end":"2026-06-09T17:59:31.498277Z","steps":["trace[928444522] 'process raft request' (duration: 119.982981ms)","trace[928444522] 'compare' (duration: 170.157676ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:31.498397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.105556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:59:31.498481Z","caller":"traceutil/trace.go:171","msg":"trace[2081942614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24565; }","duration":"128.211408ms","start":"2026-06-09T17:59:31.370252Z","end":"2026-06-09T17:59:31.498464Z","steps":["trace[2081942614] 'agreement among raft nodes before linearized reading' (duration: 128.053915ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:31.498414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.326684ms","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-06-09T17:59:31.49864Z","caller":"traceutil/trace.go:171","msg":"trace[1256608291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24565; }","duration":"659.568989ms","start":"2026-06-09T17:59:30.83906Z","end":"2026-06-09T17:59:31.498629Z","steps":["trace[1256608291] 'agreement among raft nodes before linearized reading' (duration: 659.188902ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:31.498692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:30.839044Z","time spent":"659.62178ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T17:59:31.832291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.707285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:59:31.83241Z","caller":"traceutil/trace.go:171","msg":"trace[1959640655] linearizableReadLoop","detail":"{readStateIndex:25383; appliedIndex:25382; }","duration":"184.07976ms","start":"2026-06-09T17:59:31.648311Z","end":"2026-06-09T17:59:31.832391Z","steps":["trace[1959640655] 'read index received' (duration: 65.142µs)","trace[1959640655] 'applied index is now lower than readState.Index' (duration: 184.013068ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:59:31.832444Z","caller":"traceutil/trace.go:171","msg":"trace[492646492] transaction","detail":"{read_only:false; response_revision:24566; number_of_response:1; }","duration":"330.666927ms","start":"2026-06-09T17:59:31.501741Z","end":"2026-06-09T17:59:31.832408Z","steps":["trace[492646492] 'process raft request' (duration: 138.752097ms)","trace[492646492] 'compare' (duration: 191.575783ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:31.832614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:31.501696Z","time spent":"330.8363ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:59:31.832761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.200422ms","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-06-09T17:59:31.833155Z","caller":"traceutil/trace.go:171","msg":"trace[1873970911] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24566; }","duration":"184.876965ms","start":"2026-06-09T17:59:31.64825Z","end":"2026-06-09T17:59:31.833127Z","steps":["trace[1873970911] 'agreement among raft nodes before linearized reading' (duration: 184.192432ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:32.159247Z","caller":"traceutil/trace.go:171","msg":"trace[1079321366] linearizableReadLoop","detail":"{readStateIndex:25384; appliedIndex:25383; }","duration":"208.087757ms","start":"2026-06-09T17:59:31.951141Z","end":"2026-06-09T17:59:32.159229Z","steps":["trace[1079321366] 'read index received' (duration: 207.908713ms)","trace[1079321366] 'applied index is now lower than readState.Index' (duration: 178.474µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:59:32.15962Z","caller":"traceutil/trace.go:171","msg":"trace[1321382230] transaction","detail":"{read_only:false; response_revision:24567; number_of_response:1; }","duration":"244.862716ms","start":"2026-06-09T17:59:31.914749Z","end":"2026-06-09T17:59:32.159612Z","steps":["trace[1321382230] 'process raft request' (duration: 244.359166ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:32.159851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.701337ms","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-06-09T17:59:32.159882Z","caller":"traceutil/trace.go:171","msg":"trace[712551891] 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:24567; }","duration":"208.800289ms","start":"2026-06-09T17:59:31.951075Z","end":"2026-06-09T17:59:32.159876Z","steps":["trace[712551891] 'agreement among raft nodes before linearized reading' (duration: 208.723698ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:32.160336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.178425ms","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-06-09T17:59:32.160434Z","caller":"traceutil/trace.go:171","msg":"trace[718431359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24567; }","duration":"100.346688ms","start":"2026-06-09T17:59:32.060068Z","end":"2026-06-09T17:59:32.160415Z","steps":["trace[718431359] 'agreement among raft nodes before linearized reading' (duration: 99.548853ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:32.487583Z","caller":"traceutil/trace.go:171","msg":"trace[928798135] linearizableReadLoop","detail":"{readStateIndex:25386; appliedIndex:25385; }","duration":"292.626243ms","start":"2026-06-09T17:59:32.194929Z","end":"2026-06-09T17:59:32.487555Z","steps":["trace[928798135] 'read index received' (duration: 292.352748ms)","trace[928798135] 'applied index is now lower than readState.Index' (duration: 272.195µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:59:32.487713Z","caller":"traceutil/trace.go:171","msg":"trace[1222042793] transaction","detail":"{read_only:false; response_revision:24569; number_of_response:1; }","duration":"297.212921ms","start":"2026-06-09T17:59:32.190458Z","end":"2026-06-09T17:59:32.487671Z","steps":["trace[1222042793] 'process raft request' (duration: 296.852573ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:32.48777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.817518ms","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-06-09T17:59:32.487813Z","caller":"traceutil/trace.go:171","msg":"trace[540623192] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24569; }","duration":"292.912019ms","start":"2026-06-09T17:59:32.194886Z","end":"2026-06-09T17:59:32.487798Z","steps":["trace[540623192] 'agreement among raft nodes before linearized reading' (duration: 292.793077ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:32.509477Z","caller":"traceutil/trace.go:171","msg":"trace[2129041808] transaction","detail":"{read_only:false; response_revision:24570; number_of_response:1; }","duration":"175.747181ms","start":"2026-06-09T17:59:32.333697Z","end":"2026-06-09T17:59:32.509444Z","steps":["trace[2129041808] 'process raft request' (duration: 175.309623ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:32.509712Z","caller":"traceutil/trace.go:171","msg":"trace[229507628] transaction","detail":"{read_only:false; response_revision:24571; number_of_response:1; }","duration":"166.547406ms","start":"2026-06-09T17:59:32.343154Z","end":"2026-06-09T17:59:32.509701Z","steps":["trace[229507628] 'process raft request' (duration: 165.971666ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:32.509903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.487704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:59:32.509947Z","caller":"traceutil/trace.go:171","msg":"trace[693046265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24571; }","duration":"137.582055ms","start":"2026-06-09T17:59:32.372354Z","end":"2026-06-09T17:59:32.509936Z","steps":["trace[693046265] 'agreement among raft nodes before linearized reading' (duration: 137.507894ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:33.1323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.556895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-09T17:59:33.13238Z","caller":"traceutil/trace.go:171","msg":"trace[1267218283] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:24572; }","duration":"178.700858ms","start":"2026-06-09T17:59:32.953663Z","end":"2026-06-09T17:59:33.132363Z","steps":["trace[1267218283] 'count revisions from in-memory index tree' (duration: 178.481764ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:33.300281Z","caller":"traceutil/trace.go:171","msg":"trace[867779169] linearizableReadLoop","detail":"{readStateIndex:25390; appliedIndex:25389; }","duration":"113.022779ms","start":"2026-06-09T17:59:33.187238Z","end":"2026-06-09T17:59:33.300261Z","steps":["trace[867779169] 'read index received' (duration: 112.989148ms)","trace[867779169] 'applied index is now lower than readState.Index' (duration: 32.691µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:59:33.300435Z","caller":"traceutil/trace.go:171","msg":"trace[454251317] transaction","detail":"{read_only:false; response_revision:24573; number_of_response:1; }","duration":"114.610658ms","start":"2026-06-09T17:59:33.185794Z","end":"2026-06-09T17:59:33.300405Z","steps":["trace[454251317] 'process raft request' (duration: 114.351484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:33.300535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.279413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-09T17:59:33.300563Z","caller":"traceutil/trace.go:171","msg":"trace[1441562027] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24573; }","duration":"113.351495ms","start":"2026-06-09T17:59:33.187202Z","end":"2026-06-09T17:59:33.300554Z","steps":["trace[1441562027] 'agreement among raft nodes before linearized reading' (duration: 113.129501ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:33.391242Z","caller":"traceutil/trace.go:171","msg":"trace[1142858693] transaction","detail":"{read_only:false; response_revision:24578; number_of_response:1; }","duration":"156.505456ms","start":"2026-06-09T17:59:33.234713Z","end":"2026-06-09T17:59:33.391218Z","steps":["trace[1142858693] 'process raft request' (duration: 156.468165ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:33.391585Z","caller":"traceutil/trace.go:171","msg":"trace[2140039155] transaction","detail":"{read_only:false; response_revision:24574; number_of_response:1; }","duration":"202.288455ms","start":"2026-06-09T17:59:33.189289Z","end":"2026-06-09T17:59:33.391578Z","steps":["trace[2140039155] 'process raft request' (duration: 201.48452ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:33.39168Z","caller":"traceutil/trace.go:171","msg":"trace[1046429249] transaction","detail":"{read_only:false; response_revision:24575; number_of_response:1; }","duration":"201.421409ms","start":"2026-06-09T17:59:33.190252Z","end":"2026-06-09T17:59:33.391674Z","steps":["trace[1046429249] 'process raft request' (duration: 200.838929ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:33.391769Z","caller":"traceutil/trace.go:171","msg":"trace[2045181033] transaction","detail":"{read_only:false; response_revision:24576; number_of_response:1; }","duration":"159.632555ms","start":"2026-06-09T17:59:33.232131Z","end":"2026-06-09T17:59:33.391763Z","steps":["trace[2045181033] 'process raft request' (duration: 159.000753ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:33.391852Z","caller":"traceutil/trace.go:171","msg":"trace[1290362810] transaction","detail":"{read_only:false; response_revision:24577; number_of_response:1; }","duration":"158.025994ms","start":"2026-06-09T17:59:33.233818Z","end":"2026-06-09T17:59:33.391844Z","steps":["trace[1290362810] 'process raft request' (duration: 157.336692ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:33.751193Z","caller":"traceutil/trace.go:171","msg":"trace[2018140164] transaction","detail":"{read_only:false; response_revision:24580; number_of_response:1; }","duration":"342.839178ms","start":"2026-06-09T17:59:33.40832Z","end":"2026-06-09T17:59:33.751159Z","steps":["trace[2018140164] 'process raft request' (duration: 301.321068ms)","trace[2018140164] 'compare' (duration: 41.224104ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:33.751358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:33.40828Z","time spent":"342.997451ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:59:33.762022Z","caller":"traceutil/trace.go:171","msg":"trace[61111470] transaction","detail":"{read_only:false; response_revision:24581; number_of_response:1; }","duration":"260.329839ms","start":"2026-06-09T17:59:33.501682Z","end":"2026-06-09T17:59:33.762011Z","steps":["trace[61111470] 'process raft request' (duration: 260.049084ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:33.761833Z","caller":"traceutil/trace.go:171","msg":"trace[677641559] linearizableReadLoop","detail":"{readStateIndex:25398; appliedIndex:25396; }","duration":"200.996642ms","start":"2026-06-09T17:59:33.560819Z","end":"2026-06-09T17:59:33.761815Z","steps":["trace[677641559] 'read index received' (duration: 148.821549ms)","trace[677641559] 'applied index is now lower than readState.Index' (duration: 52.174073ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:33.762384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.551782ms","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-06-09T17:59:33.762409Z","caller":"traceutil/trace.go:171","msg":"trace[101279766] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24581; }","duration":"201.630374ms","start":"2026-06-09T17:59:33.56077Z","end":"2026-06-09T17:59:33.762401Z","steps":["trace[101279766] 'agreement among raft nodes before linearized reading' (duration: 201.538572ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:33.964908Z","caller":"traceutil/trace.go:171","msg":"trace[164001892] transaction","detail":"{read_only:false; response_revision:24582; number_of_response:1; }","duration":"105.293223ms","start":"2026-06-09T17:59:33.859579Z","end":"2026-06-09T17:59:33.964872Z","steps":["trace[164001892] 'process raft request' (duration: 104.62218ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:34.137967Z","caller":"traceutil/trace.go:171","msg":"trace[869221015] transaction","detail":"{read_only:false; response_revision:24583; number_of_response:1; }","duration":"121.615862ms","start":"2026-06-09T17:59:34.0163Z","end":"2026-06-09T17:59:34.137916Z","steps":["trace[869221015] 'process raft request' (duration: 110.237266ms)","trace[869221015] 'compare' (duration: 11.0341ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:34.691089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.31991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:59:34.691184Z","caller":"traceutil/trace.go:171","msg":"trace[528600189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24584; }","duration":"319.455763ms","start":"2026-06-09T17:59:34.371706Z","end":"2026-06-09T17:59:34.691162Z","steps":["trace[528600189] 'range keys from in-memory index tree' (duration: 319.223689ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:34.691222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.290035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:59:34.691246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:34.371687Z","time spent":"319.533484ms","remote":"127.0.0.1:44324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T17:59:34.691282Z","caller":"traceutil/trace.go:171","msg":"trace[918240745] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24584; }","duration":"314.399637ms","start":"2026-06-09T17:59:34.376868Z","end":"2026-06-09T17:59:34.691267Z","steps":["trace[918240745] 'range keys from in-memory index tree' (duration: 314.192663ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:34.691312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:34.376845Z","time spent":"314.460898ms","remote":"127.0.0.1:44740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-06-09T17:59:34.889249Z","caller":"traceutil/trace.go:171","msg":"trace[1275403198] transaction","detail":"{read_only:false; response_revision:24585; number_of_response:1; }","duration":"368.20321ms","start":"2026-06-09T17:59:34.52102Z","end":"2026-06-09T17:59:34.889223Z","steps":["trace[1275403198] 'process raft request' (duration: 368.058457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:34.889405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:34.520992Z","time spent":"368.335673ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:59:35.077881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.159565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:59:35.077993Z","caller":"traceutil/trace.go:171","msg":"trace[557134810] linearizableReadLoop","detail":"{readStateIndex:25403; appliedIndex:25401; }","duration":"469.958884ms","start":"2026-06-09T17:59:34.608017Z","end":"2026-06-09T17:59:35.077976Z","steps":["trace[557134810] 'read index received' (duration: 281.001652ms)","trace[557134810] 'applied index is now lower than readState.Index' (duration: 188.956022ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:35.07817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.149418ms","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-06-09T17:59:35.078219Z","caller":"traceutil/trace.go:171","msg":"trace[27938883] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24586; }","duration":"470.22204ms","start":"2026-06-09T17:59:34.607987Z","end":"2026-06-09T17:59:35.078209Z","steps":["trace[27938883] 'agreement among raft nodes before linearized reading' (duration: 470.071557ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:35.078261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:34.607974Z","time spent":"470.27917ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":557,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-06-09T17:59:35.078182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.365299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-09T17:59:35.078506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.194514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:59:35.078503Z","caller":"traceutil/trace.go:171","msg":"trace[2081382084] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:24586; }","duration":"422.724116ms","start":"2026-06-09T17:59:34.655743Z","end":"2026-06-09T17:59:35.078467Z","steps":["trace[2081382084] 'agreement among raft nodes before linearized reading' (duration: 422.35082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:35.078535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.603391ms","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-06-09T17:59:35.078545Z","caller":"traceutil/trace.go:171","msg":"trace[810189486] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24586; }","duration":"384.269075ms","start":"2026-06-09T17:59:34.694263Z","end":"2026-06-09T17:59:35.078532Z","steps":["trace[810189486] 'agreement among raft nodes before linearized reading' (duration: 384.212054ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:35.078597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:34.694249Z","time spent":"384.335327ms","remote":"127.0.0.1:44482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-06-09T17:59:35.078598Z","caller":"traceutil/trace.go:171","msg":"trace[316118106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24586; }","duration":"232.706933ms","start":"2026-06-09T17:59:34.845869Z","end":"2026-06-09T17:59:35.078576Z","steps":["trace[316118106] 'agreement among raft nodes before linearized reading' (duration: 232.567321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:35.078627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:34.655724Z","time spent":"422.859778ms","remote":"127.0.0.1:44672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":32,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:59:35.079047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.537506ms","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-06-09T17:59:35.079282Z","caller":"traceutil/trace.go:171","msg":"trace[1018160059] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:24586; }","duration":"257.945903ms","start":"2026-06-09T17:59:34.821184Z","end":"2026-06-09T17:59:35.07913Z","steps":["trace[1018160059] 'agreement among raft nodes before linearized reading' (duration: 257.297052ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:35.079743Z","caller":"traceutil/trace.go:171","msg":"trace[1253402047] transaction","detail":"{read_only:false; response_revision:24586; number_of_response:1; }","duration":"533.137254ms","start":"2026-06-09T17:59:34.546099Z","end":"2026-06-09T17:59:35.079237Z","steps":["trace[1253402047] 'process raft request' (duration: 361.532313ms)","trace[1253402047] 'compare' (duration: 169.861379ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:35.080154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:34.546079Z","time spent":"533.87938ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:59:35.225987Z","caller":"traceutil/trace.go:171","msg":"trace[2110997000] linearizableReadLoop","detail":"{readStateIndex:25404; appliedIndex:25403; }","duration":"141.02011ms","start":"2026-06-09T17:59:35.084948Z","end":"2026-06-09T17:59:35.225968Z","steps":["trace[2110997000] 'read index received' (duration: 140.872477ms)","trace[2110997000] 'applied index is now lower than readState.Index' (duration: 146.533µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:59:35.226017Z","caller":"traceutil/trace.go:171","msg":"trace[1130559858] transaction","detail":"{read_only:false; response_revision:24587; number_of_response:1; }","duration":"143.029209ms","start":"2026-06-09T17:59:35.082969Z","end":"2026-06-09T17:59:35.225998Z","steps":["trace[1130559858] 'process raft request' (duration: 142.876296ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:35.22617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.202303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18368"} {"level":"info","ts":"2026-06-09T17:59:35.226199Z","caller":"traceutil/trace.go:171","msg":"trace[1558575447] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24587; }","duration":"141.285185ms","start":"2026-06-09T17:59:35.084906Z","end":"2026-06-09T17:59:35.226191Z","steps":["trace[1558575447] 'agreement among raft nodes before linearized reading' (duration: 141.143363ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:35.265469Z","caller":"traceutil/trace.go:171","msg":"trace[400149759] transaction","detail":"{read_only:false; response_revision:24588; number_of_response:1; }","duration":"172.486649ms","start":"2026-06-09T17:59:35.092949Z","end":"2026-06-09T17:59:35.265435Z","steps":["trace[400149759] 'process raft request' (duration: 171.797337ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:35.455396Z","caller":"traceutil/trace.go:171","msg":"trace[82284120] linearizableReadLoop","detail":"{readStateIndex:25406; appliedIndex:25405; }","duration":"143.500037ms","start":"2026-06-09T17:59:35.311867Z","end":"2026-06-09T17:59:35.455367Z","steps":["trace[82284120] 'read index received' (duration: 122.825214ms)","trace[82284120] 'applied index is now lower than readState.Index' (duration: 20.673603ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:59:35.455515Z","caller":"traceutil/trace.go:171","msg":"trace[1303168845] transaction","detail":"{read_only:false; response_revision:24589; number_of_response:1; }","duration":"144.12301ms","start":"2026-06-09T17:59:35.311378Z","end":"2026-06-09T17:59:35.455501Z","steps":["trace[1303168845] 'process raft request' (duration: 123.353705ms)","trace[1303168845] 'compare' (duration: 20.50907ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:35.455768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.886804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.151\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-09T17:59:35.455809Z","caller":"traceutil/trace.go:171","msg":"trace[344341804] range","detail":"{range_begin:/registry/masterleases/199.19.213.151; range_end:; response_count:1; response_revision:24589; }","duration":"144.003066ms","start":"2026-06-09T17:59:35.311796Z","end":"2026-06-09T17:59:35.4558Z","steps":["trace[344341804] 'agreement among raft nodes before linearized reading' (duration: 143.843323ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:35.710166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.405202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:59:35.710808Z","caller":"traceutil/trace.go:171","msg":"trace[1166511644] linearizableReadLoop","detail":"{readStateIndex:25409; appliedIndex:25406; }","duration":"255.321475ms","start":"2026-06-09T17:59:35.455464Z","end":"2026-06-09T17:59:35.710785Z","steps":["trace[1166511644] 'read index received' (duration: 126.275591ms)","trace[1166511644] 'applied index is now lower than readState.Index' (duration: 129.044374ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:59:35.710847Z","caller":"traceutil/trace.go:171","msg":"trace[868145394] transaction","detail":"{read_only:false; response_revision:24592; number_of_response:1; }","duration":"310.043925ms","start":"2026-06-09T17:59:35.400728Z","end":"2026-06-09T17:59:35.710772Z","steps":["trace[868145394] 'process raft request' (duration: 309.594115ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:35.71108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.242085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-09T17:59:35.711126Z","caller":"traceutil/trace.go:171","msg":"trace[1754483428] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24592; }","duration":"304.288846ms","start":"2026-06-09T17:59:35.406825Z","end":"2026-06-09T17:59:35.711114Z","steps":["trace[1754483428] 'agreement among raft nodes before linearized reading' (duration: 304.162203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:35.71116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:35.406777Z","time spent":"304.374187ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-09T17:59:35.711179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:35.400678Z","time spent":"310.32463ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:59:35.711352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.767518ms","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-06-09T17:59:35.711387Z","caller":"traceutil/trace.go:171","msg":"trace[1970782983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24592; }","duration":"310.85907ms","start":"2026-06-09T17:59:35.40052Z","end":"2026-06-09T17:59:35.711379Z","steps":["trace[1970782983] 'agreement among raft nodes before linearized reading' (duration: 310.770928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:35.711413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:35.40049Z","time spent":"310.917261ms","remote":"127.0.0.1:44556","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-06-09T17:59:35.711529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.012482ms","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-06-09T17:59:35.711561Z","caller":"traceutil/trace.go:171","msg":"trace[52465879] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24592; }","duration":"311.070824ms","start":"2026-06-09T17:59:35.400483Z","end":"2026-06-09T17:59:35.711553Z","steps":["trace[52465879] 'agreement among raft nodes before linearized reading' (duration: 310.991152ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:35.711586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:35.400467Z","time spent":"311.112834ms","remote":"127.0.0.1:44556","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-06-09T17:59:35.711663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.114939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:59:35.711694Z","caller":"traceutil/trace.go:171","msg":"trace[938276274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24592; }","duration":"337.17584ms","start":"2026-06-09T17:59:35.374508Z","end":"2026-06-09T17:59:35.711684Z","steps":["trace[938276274] 'agreement among raft nodes before linearized reading' (duration: 337.136299ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:35.711717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:35.37449Z","time spent":"337.222031ms","remote":"127.0.0.1:44322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T17:59:35.711894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.268133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:59:35.711664Z","caller":"traceutil/trace.go:171","msg":"trace[255034165] transaction","detail":"{read_only:false; response_revision:24590; number_of_response:1; }","duration":"313.321736ms","start":"2026-06-09T17:59:35.398323Z","end":"2026-06-09T17:59:35.711645Z","steps":["trace[255034165] 'process raft request' (duration: 183.353005ms)","trace[255034165] 'compare' (duration: 128.262359ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:59:35.711925Z","caller":"traceutil/trace.go:171","msg":"trace[1051215333] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:24592; }","duration":"244.322574ms","start":"2026-06-09T17:59:35.467593Z","end":"2026-06-09T17:59:35.711915Z","steps":["trace[1051215333] 'agreement among raft nodes before linearized reading' (duration: 244.269983ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:35.712174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.067014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:59:35.712216Z","caller":"traceutil/trace.go:171","msg":"trace[1268832780] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24592; }","duration":"159.209787ms","start":"2026-06-09T17:59:35.552995Z","end":"2026-06-09T17:59:35.712205Z","steps":["trace[1268832780] 'agreement among raft nodes before linearized reading' (duration: 159.119055ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:35.712281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:35.398301Z","time spent":"313.650243ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:59:35.71233Z","caller":"traceutil/trace.go:171","msg":"trace[750090238] transaction","detail":"{read_only:false; response_revision:24591; number_of_response:1; }","duration":"311.837818ms","start":"2026-06-09T17:59:35.400462Z","end":"2026-06-09T17:59:35.7123Z","steps":["trace[750090238] 'process raft request' (duration: 309.808959ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:35.712519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:35.400455Z","time spent":"311.971171ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:59:35.913947Z","caller":"traceutil/trace.go:171","msg":"trace[104694089] transaction","detail":"{read_only:false; response_revision:24595; number_of_response:1; }","duration":"196.610969ms","start":"2026-06-09T17:59:35.717311Z","end":"2026-06-09T17:59:35.913922Z","steps":["trace[104694089] 'process raft request' (duration: 196.473336ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:35.914144Z","caller":"traceutil/trace.go:171","msg":"trace[1363676197] linearizableReadLoop","detail":"{readStateIndex:25414; appliedIndex:25411; }","duration":"181.271546ms","start":"2026-06-09T17:59:35.732847Z","end":"2026-06-09T17:59:35.914118Z","steps":["trace[1363676197] 'read index received' (duration: 180.894849ms)","trace[1363676197] 'applied index is now lower than readState.Index' (duration: 375.677µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:59:35.914222Z","caller":"traceutil/trace.go:171","msg":"trace[1291330503] transaction","detail":"{read_only:false; response_revision:24594; number_of_response:1; }","duration":"198.368712ms","start":"2026-06-09T17:59:35.71584Z","end":"2026-06-09T17:59:35.914208Z","steps":["trace[1291330503] 'process raft request' (duration: 197.78529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:35.914363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.714355ms","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-06-09T17:59:35.914401Z","caller":"traceutil/trace.go:171","msg":"trace[1574472669] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24596; }","duration":"181.812736ms","start":"2026-06-09T17:59:35.732574Z","end":"2026-06-09T17:59:35.914387Z","steps":["trace[1574472669] 'agreement among raft nodes before linearized reading' (duration: 181.645323ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:35.914618Z","caller":"traceutil/trace.go:171","msg":"trace[1533255826] transaction","detail":"{read_only:false; response_revision:24596; number_of_response:1; }","duration":"196.610757ms","start":"2026-06-09T17:59:35.717996Z","end":"2026-06-09T17:59:35.914607Z","steps":["trace[1533255826] 'process raft request' (duration: 195.875864ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:35.928092Z","caller":"traceutil/trace.go:171","msg":"trace[1354782978] transaction","detail":"{read_only:false; response_revision:24597; number_of_response:1; }","duration":"161.336197ms","start":"2026-06-09T17:59:35.766726Z","end":"2026-06-09T17:59:35.928062Z","steps":["trace[1354782978] 'process raft request' (duration: 161.153174ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:36.114654Z","caller":"traceutil/trace.go:171","msg":"trace[182874885] transaction","detail":"{read_only:false; response_revision:24598; number_of_response:1; }","duration":"141.906658ms","start":"2026-06-09T17:59:35.972727Z","end":"2026-06-09T17:59:36.114634Z","steps":["trace[182874885] 'process raft request' (duration: 114.285213ms)","trace[182874885] 'compare' (duration: 27.062335ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:36.421735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.443934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:59:36.421799Z","caller":"traceutil/trace.go:171","msg":"trace[1786035324] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:24598; }","duration":"105.529066ms","start":"2026-06-09T17:59:36.31625Z","end":"2026-06-09T17:59:36.421779Z","steps":["trace[1786035324] 'count revisions from in-memory index tree' (duration: 105.411803ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:36.422087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.533616ms","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-06-09T17:59:36.422168Z","caller":"traceutil/trace.go:171","msg":"trace[885630106] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24598; }","duration":"146.651759ms","start":"2026-06-09T17:59:36.275499Z","end":"2026-06-09T17:59:36.422151Z","steps":["trace[885630106] 'range keys from in-memory index tree' (duration: 146.374562ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:36.631493Z","caller":"traceutil/trace.go:171","msg":"trace[1314898482] transaction","detail":"{read_only:false; response_revision:24600; number_of_response:1; }","duration":"114.146719ms","start":"2026-06-09T17:59:36.517301Z","end":"2026-06-09T17:59:36.631447Z","steps":["trace[1314898482] 'process raft request' (duration: 106.327921ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:37.151683Z","caller":"traceutil/trace.go:171","msg":"trace[883928284] linearizableReadLoop","detail":"{readStateIndex:25419; appliedIndex:25418; }","duration":"234.754623ms","start":"2026-06-09T17:59:36.916904Z","end":"2026-06-09T17:59:37.151659Z","steps":["trace[883928284] 'read index received' (duration: 234.615461ms)","trace[883928284] 'applied index is now lower than readState.Index' (duration: 138.262µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:37.152042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.12366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-09T17:59:37.15209Z","caller":"traceutil/trace.go:171","msg":"trace[273563879] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:24601; }","duration":"235.236452ms","start":"2026-06-09T17:59:36.916841Z","end":"2026-06-09T17:59:37.152078Z","steps":["trace[273563879] 'agreement among raft nodes before linearized reading' (duration: 234.926006ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:37.152475Z","caller":"traceutil/trace.go:171","msg":"trace[1903938614] transaction","detail":"{read_only:false; response_revision:24601; number_of_response:1; }","duration":"257.302092ms","start":"2026-06-09T17:59:36.895159Z","end":"2026-06-09T17:59:37.152461Z","steps":["trace[1903938614] 'process raft request' (duration: 256.357975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:37.422409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.367849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:59:37.422524Z","caller":"traceutil/trace.go:171","msg":"trace[527196931] linearizableReadLoop","detail":"{readStateIndex:25421; appliedIndex:25420; }","duration":"214.528858ms","start":"2026-06-09T17:59:37.207977Z","end":"2026-06-09T17:59:37.422506Z","steps":["trace[527196931] 'read index received' (duration: 84.915014ms)","trace[527196931] 'applied index is now lower than readState.Index' (duration: 129.612644ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:59:37.422617Z","caller":"traceutil/trace.go:171","msg":"trace[67717357] transaction","detail":"{read_only:false; response_revision:24603; number_of_response:1; }","duration":"258.216619ms","start":"2026-06-09T17:59:37.164388Z","end":"2026-06-09T17:59:37.422605Z","steps":["trace[67717357] 'process raft request' (duration: 128.559124ms)","trace[67717357] 'compare' (duration: 129.087474ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:37.423004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.642925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-09T17:59:37.42306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.091109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:59:37.423094Z","caller":"traceutil/trace.go:171","msg":"trace[555050424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24603; }","duration":"148.773278ms","start":"2026-06-09T17:59:37.274299Z","end":"2026-06-09T17:59:37.423072Z","steps":["trace[555050424] 'agreement among raft nodes before linearized reading' (duration: 148.555194ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:37.423099Z","caller":"traceutil/trace.go:171","msg":"trace[392856361] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:24603; }","duration":"215.135671ms","start":"2026-06-09T17:59:37.207952Z","end":"2026-06-09T17:59:37.423088Z","steps":["trace[392856361] 'agreement among raft nodes before linearized reading' (duration: 215.061328ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:37.568358Z","caller":"traceutil/trace.go:171","msg":"trace[1361135946] transaction","detail":"{read_only:false; response_revision:24605; number_of_response:1; }","duration":"106.378862ms","start":"2026-06-09T17:59:37.461949Z","end":"2026-06-09T17:59:37.568328Z","steps":["trace[1361135946] 'process raft request' (duration: 104.749901ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:39.795768Z","caller":"traceutil/trace.go:171","msg":"trace[1279695840] transaction","detail":"{read_only:false; response_revision:24619; number_of_response:1; }","duration":"220.252327ms","start":"2026-06-09T17:59:39.575481Z","end":"2026-06-09T17:59:39.795733Z","steps":["trace[1279695840] 'process raft request' (duration: 195.751081ms)","trace[1279695840] 'compare' (duration: 24.309372ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:40.310293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.172361ms","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-06-09T17:59:40.310364Z","caller":"traceutil/trace.go:171","msg":"trace[625770563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24622; }","duration":"378.273862ms","start":"2026-06-09T17:59:39.932072Z","end":"2026-06-09T17:59:40.310346Z","steps":["trace[625770563] 'range keys from in-memory index tree' (duration: 377.958185ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:40.310397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:39.932059Z","time spent":"378.331523ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T17:59:40.310295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.988064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-06-09T17:59:40.310433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.805668ms","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-06-09T17:59:40.310465Z","caller":"traceutil/trace.go:171","msg":"trace[1715772749] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24622; }","duration":"381.219938ms","start":"2026-06-09T17:59:39.929222Z","end":"2026-06-09T17:59:40.310442Z","steps":["trace[1715772749] 'range keys from in-memory index tree' (duration: 380.778248ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:40.310503Z","caller":"traceutil/trace.go:171","msg":"trace[1767089977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24622; }","duration":"381.933171ms","start":"2026-06-09T17:59:39.928552Z","end":"2026-06-09T17:59:40.310485Z","steps":["trace[1767089977] 'range keys from in-memory index tree' (duration: 381.580775ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:40.310518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:39.929197Z","time spent":"381.307899ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":583,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-09T17:59:40.310555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:39.928521Z","time spent":"382.018623ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T17:59:40.328221Z","caller":"traceutil/trace.go:171","msg":"trace[994185418] linearizableReadLoop","detail":"{readStateIndex:25441; appliedIndex:25440; }","duration":"224.945606ms","start":"2026-06-09T17:59:40.103261Z","end":"2026-06-09T17:59:40.328207Z","steps":["trace[994185418] 'read index received' (duration: 224.884265ms)","trace[994185418] 'applied index is now lower than readState.Index' (duration: 60.441µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:40.328368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.082018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:59:40.328287Z","caller":"traceutil/trace.go:171","msg":"trace[283629533] transaction","detail":"{read_only:false; response_revision:24623; number_of_response:1; }","duration":"389.211749ms","start":"2026-06-09T17:59:39.939048Z","end":"2026-06-09T17:59:40.32826Z","steps":["trace[283629533] 'process raft request' (duration: 389.017256ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:40.328432Z","caller":"traceutil/trace.go:171","msg":"trace[870813905] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:24623; }","duration":"225.232962ms","start":"2026-06-09T17:59:40.103182Z","end":"2026-06-09T17:59:40.328415Z","steps":["trace[870813905] 'agreement among raft nodes before linearized reading' (duration: 225.088138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:40.328576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:39.939023Z","time spent":"389.412755ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:59:40.32975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.242316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T17:59:40.329859Z","caller":"traceutil/trace.go:171","msg":"trace[483601267] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:24626; }","duration":"149.424921ms","start":"2026-06-09T17:59:40.180419Z","end":"2026-06-09T17:59:40.329844Z","steps":["trace[483601267] 'agreement among raft nodes before linearized reading' (duration: 149.268127ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:40.329851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.461479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-09T17:59:40.329908Z","caller":"traceutil/trace.go:171","msg":"trace[1580733513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24626; }","duration":"203.562761ms","start":"2026-06-09T17:59:40.126331Z","end":"2026-06-09T17:59:40.329894Z","steps":["trace[1580733513] 'agreement among raft nodes before linearized reading' (duration: 203.349256ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:50.742977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.256422ms","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-06-09T17:59:50.742993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.806038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-06-09T17:59:50.743094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.777316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fda2786c-8270-41e9-bc64-41d504da396f\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-06-09T17:59:50.74313Z","caller":"traceutil/trace.go:171","msg":"trace[480328590] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24762; }","duration":"201.055713ms","start":"2026-06-09T17:59:50.542047Z","end":"2026-06-09T17:59:50.743103Z","steps":["trace[480328590] 'range keys from in-memory index tree' (duration: 200.526153ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:50.743147Z","caller":"traceutil/trace.go:171","msg":"trace[1419234109] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fda2786c-8270-41e9-bc64-41d504da396f; range_end:; response_count:1; response_revision:24762; }","duration":"212.866587ms","start":"2026-06-09T17:59:50.530255Z","end":"2026-06-09T17:59:50.743121Z","steps":["trace[1419234109] 'range keys from in-memory index tree' (duration: 212.272206ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:50.743062Z","caller":"traceutil/trace.go:171","msg":"trace[832258425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24762; }","duration":"236.414515ms","start":"2026-06-09T17:59:50.506629Z","end":"2026-06-09T17:59:50.743043Z","steps":["trace[832258425] 'range keys from in-memory index tree' (duration: 236.060188ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:51.097487Z","caller":"traceutil/trace.go:171","msg":"trace[480382282] linearizableReadLoop","detail":"{readStateIndex:25585; appliedIndex:25584; }","duration":"205.301374ms","start":"2026-06-09T17:59:50.892156Z","end":"2026-06-09T17:59:51.097458Z","steps":["trace[480382282] 'read index received' (duration: 138.693797ms)","trace[480382282] 'applied index is now lower than readState.Index' (duration: 66.606206ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:51.097606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.437746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:59:51.097634Z","caller":"traceutil/trace.go:171","msg":"trace[1595286217] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24764; }","duration":"205.508828ms","start":"2026-06-09T17:59:50.892113Z","end":"2026-06-09T17:59:51.097622Z","steps":["trace[1595286217] 'agreement among raft nodes before linearized reading' (duration: 205.441386ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:51.097699Z","caller":"traceutil/trace.go:171","msg":"trace[1763981839] transaction","detail":"{read_only:false; response_revision:24764; number_of_response:1; }","duration":"278.862041ms","start":"2026-06-09T17:59:50.818792Z","end":"2026-06-09T17:59:51.097654Z","steps":["trace[1763981839] 'process raft request' (duration: 212.196814ms)","trace[1763981839] 'compare' (duration: 66.25741ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:51.581204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.793069ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-06-09T17:59:51.581666Z","caller":"traceutil/trace.go:171","msg":"trace[763903] linearizableReadLoop","detail":"{readStateIndex:25588; appliedIndex:25585; }","duration":"327.131159ms","start":"2026-06-09T17:59:51.254508Z","end":"2026-06-09T17:59:51.581639Z","steps":["trace[763903] 'read index received' (duration: 36.891442ms)","trace[763903] 'applied index is now lower than readState.Index' (duration: 290.238087ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:59:51.581724Z","caller":"traceutil/trace.go:171","msg":"trace[703235174] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"379.22465ms","start":"2026-06-09T17:59:51.202429Z","end":"2026-06-09T17:59:51.581654Z","steps":["trace[703235174] 'process raft request' (duration: 378.926994ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:51.581799Z","caller":"traceutil/trace.go:171","msg":"trace[585071173] transaction","detail":"{read_only:false; response_revision:24766; number_of_response:1; }","duration":"369.513645ms","start":"2026-06-09T17:59:51.212272Z","end":"2026-06-09T17:59:51.581785Z","steps":["trace[585071173] 'process raft request' (duration: 369.288931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:51.581937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:51.212258Z","time spent":"369.583226ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:59:51.581999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:51.202403Z","time spent":"379.442453ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:59:51.582003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.330959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:59:51.582094Z","caller":"traceutil/trace.go:171","msg":"trace[1930401650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24766; }","duration":"210.497251ms","start":"2026-06-09T17:59:51.371576Z","end":"2026-06-09T17:59:51.582073Z","steps":["trace[1930401650] 'agreement among raft nodes before linearized reading' (duration: 210.309698ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:51.582307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.787181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:59:51.582369Z","caller":"traceutil/trace.go:171","msg":"trace[1852906332] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:24766; }","duration":"327.889384ms","start":"2026-06-09T17:59:51.254464Z","end":"2026-06-09T17:59:51.582353Z","steps":["trace[1852906332] 'agreement among raft nodes before linearized reading' (duration: 327.751131ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:51.582409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:51.254444Z","time spent":"327.954384ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":30,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:59:51.872399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.388392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:59:51.872593Z","caller":"traceutil/trace.go:171","msg":"trace[309354927] linearizableReadLoop","detail":"{readStateIndex:25589; appliedIndex:25588; }","duration":"283.843257ms","start":"2026-06-09T17:59:51.588726Z","end":"2026-06-09T17:59:51.872569Z","steps":["trace[309354927] 'read index received' (duration: 126.121728ms)","trace[309354927] 'applied index is now lower than readState.Index' (duration: 157.719849ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:59:51.87271Z","caller":"traceutil/trace.go:171","msg":"trace[1374701653] transaction","detail":"{read_only:false; response_revision:24767; number_of_response:1; }","duration":"285.114341ms","start":"2026-06-09T17:59:51.587582Z","end":"2026-06-09T17:59:51.872696Z","steps":["trace[1374701653] 'process raft request' (duration: 127.331121ms)","trace[1374701653] 'compare' (duration: 157.137937ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:51.873007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.252304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-06-09T17:59:51.873106Z","caller":"traceutil/trace.go:171","msg":"trace[991382906] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:24767; }","duration":"284.391166ms","start":"2026-06-09T17:59:51.588695Z","end":"2026-06-09T17:59:51.873086Z","steps":["trace[991382906] 'agreement among raft nodes before linearized reading' (duration: 284.117281ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:52.004478Z","caller":"traceutil/trace.go:171","msg":"trace[75744104] transaction","detail":"{read_only:false; response_revision:24768; number_of_response:1; }","duration":"167.659478ms","start":"2026-06-09T17:59:51.836787Z","end":"2026-06-09T17:59:52.004447Z","steps":["trace[75744104] 'process raft request' (duration: 138.046005ms)","trace[75744104] 'compare' (duration: 29.46713ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:59:52.265082Z","caller":"traceutil/trace.go:171","msg":"trace[1320290620] linearizableReadLoop","detail":"{readStateIndex:25593; appliedIndex:25589; }","duration":"392.4427ms","start":"2026-06-09T17:59:51.872622Z","end":"2026-06-09T17:59:52.265065Z","steps":["trace[1320290620] 'read index received' (duration: 102.226543ms)","trace[1320290620] 'applied index is now lower than readState.Index' (duration: 290.215387ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:59:52.265391Z","caller":"traceutil/trace.go:171","msg":"trace[1188805087] transaction","detail":"{read_only:false; response_revision:24771; number_of_response:1; }","duration":"418.905074ms","start":"2026-06-09T17:59:51.846455Z","end":"2026-06-09T17:59:52.26536Z","steps":["trace[1188805087] 'process raft request' (duration: 418.569677ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:52.265489Z","caller":"traceutil/trace.go:171","msg":"trace[749965985] transaction","detail":"{read_only:false; response_revision:24769; number_of_response:1; }","duration":"426.454106ms","start":"2026-06-09T17:59:51.839008Z","end":"2026-06-09T17:59:52.265463Z","steps":["trace[749965985] 'process raft request' (duration: 377.987826ms)","trace[749965985] 'compare' (duration: 47.704817ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:52.265567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:51.846443Z","time spent":"419.032336ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:59:52.265555Z","caller":"traceutil/trace.go:171","msg":"trace[2147436770] transaction","detail":"{read_only:false; response_revision:24770; number_of_response:1; }","duration":"421.121586ms","start":"2026-06-09T17:59:51.844393Z","end":"2026-06-09T17:59:52.265515Z","steps":["trace[2147436770] 'process raft request' (duration: 420.563826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:52.265636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:51.838926Z","time spent":"426.62362ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:59:52.265741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:51.844358Z","time spent":"421.287109ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:59:52.265821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.447946ms","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-06-09T17:59:52.265868Z","caller":"traceutil/trace.go:171","msg":"trace[892436795] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:24771; }","duration":"559.537187ms","start":"2026-06-09T17:59:51.706314Z","end":"2026-06-09T17:59:52.265852Z","steps":["trace[892436795] 'agreement among raft nodes before linearized reading' (duration: 559.462525ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:52.265902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:51.706289Z","time spent":"559.604049ms","remote":"127.0.0.1:59642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:59:52.265953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.998558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fda2786c-8270-41e9-bc64-41d504da396f\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-06-09T17:59:52.265988Z","caller":"traceutil/trace.go:171","msg":"trace[1794480926] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fda2786c-8270-41e9-bc64-41d504da396f; range_end:; response_count:1; response_revision:24771; }","duration":"518.09878ms","start":"2026-06-09T17:59:51.74788Z","end":"2026-06-09T17:59:52.265978Z","steps":["trace[1794480926] 'agreement among raft nodes before linearized reading' (duration: 517.508028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:52.266017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:51.747848Z","time spent":"518.16109ms","remote":"127.0.0.1:44476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3788,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fda2786c-8270-41e9-bc64-41d504da396f\" "} {"level":"warn","ts":"2026-06-09T17:59:52.266119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.845505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"warn","ts":"2026-06-09T17:59:52.266096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.967145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18b77b852975681a\" ","response":"range_response_count:1 size:1010"} {"level":"warn","ts":"2026-06-09T17:59:52.266209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.420477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:59:52.266182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.557666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T17:59:52.266243Z","caller":"traceutil/trace.go:171","msg":"trace[885004437] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:24771; }","duration":"355.501079ms","start":"2026-06-09T17:59:51.910733Z","end":"2026-06-09T17:59:52.266234Z","steps":["trace[885004437] 'agreement among raft nodes before linearized reading' (duration: 355.445738ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:52.266239Z","caller":"traceutil/trace.go:171","msg":"trace[245480462] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18b77b852975681a; range_end:; response_count:1; response_revision:24771; }","duration":"642.135088ms","start":"2026-06-09T17:59:51.624065Z","end":"2026-06-09T17:59:52.2662Z","steps":["trace[245480462] 'agreement among raft nodes before linearized reading' (duration: 641.666218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:52.266267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:51.910704Z","time spent":"355.55683ms","remote":"127.0.0.1:37126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:59:52.266157Z","caller":"traceutil/trace.go:171","msg":"trace[1727404482] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:24771; }","duration":"386.918886ms","start":"2026-06-09T17:59:51.87923Z","end":"2026-06-09T17:59:52.266149Z","steps":["trace[1727404482] 'agreement among raft nodes before linearized reading' (duration: 386.741953ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:52.266331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:51.624048Z","time spent":"642.26745ms","remote":"127.0.0.1:44374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1034,"request content":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18b77b852975681a\" "} {"level":"warn","ts":"2026-06-09T17:59:52.266113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.69ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"warn","ts":"2026-06-09T17:59:52.266362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:51.879214Z","time spent":"387.136061ms","remote":"127.0.0.1:44482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1282,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" "} {"level":"info","ts":"2026-06-09T17:59:52.26649Z","caller":"traceutil/trace.go:171","msg":"trace[1623719962] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:24771; }","duration":"146.067817ms","start":"2026-06-09T17:59:52.120407Z","end":"2026-06-09T17:59:52.266474Z","steps":["trace[1623719962] 'agreement among raft nodes before linearized reading' (duration: 145.566778ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:52.266259Z","caller":"traceutil/trace.go:171","msg":"trace[1388191406] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:24771; }","duration":"166.702599ms","start":"2026-06-09T17:59:52.09954Z","end":"2026-06-09T17:59:52.266243Z","steps":["trace[1388191406] 'agreement among raft nodes before linearized reading' (duration: 166.574256ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:52.685326Z","caller":"traceutil/trace.go:171","msg":"trace[1698011910] linearizableReadLoop","detail":"{readStateIndex:25596; appliedIndex:25595; }","duration":"337.682779ms","start":"2026-06-09T17:59:52.347617Z","end":"2026-06-09T17:59:52.6853Z","steps":["trace[1698011910] 'read index received' (duration: 294.934457ms)","trace[1698011910] 'applied index is now lower than readState.Index' (duration: 42.747382ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:59:52.685358Z","caller":"traceutil/trace.go:171","msg":"trace[572042217] transaction","detail":"{read_only:false; response_revision:24774; number_of_response:1; }","duration":"344.375097ms","start":"2026-06-09T17:59:52.340933Z","end":"2026-06-09T17:59:52.685308Z","steps":["trace[572042217] 'process raft request' (duration: 301.599065ms)","trace[572042217] 'compare' (duration: 42.62831ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:52.685653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.987895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-06-09T17:59:52.685721Z","caller":"traceutil/trace.go:171","msg":"trace[1359123086] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24774; }","duration":"338.108968ms","start":"2026-06-09T17:59:52.347596Z","end":"2026-06-09T17:59:52.685705Z","steps":["trace[1359123086] 'agreement among raft nodes before linearized reading' (duration: 337.792822ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:52.685773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:52.347587Z","time spent":"338.164828ms","remote":"127.0.0.1:44754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":9626,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" "} {"level":"warn","ts":"2026-06-09T17:59:52.686762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:52.340908Z","time spent":"344.54593ms","remote":"127.0.0.1:44374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":718,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:59:52.715479Z","caller":"traceutil/trace.go:171","msg":"trace[1920468031] transaction","detail":"{read_only:false; response_revision:24775; number_of_response:1; }","duration":"340.104886ms","start":"2026-06-09T17:59:52.375349Z","end":"2026-06-09T17:59:52.715454Z","steps":["trace[1920468031] 'process raft request' (duration: 339.82335ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:52.715598Z","caller":"traceutil/trace.go:171","msg":"trace[1254166114] transaction","detail":"{read_only:false; response_revision:24776; number_of_response:1; }","duration":"270.799789ms","start":"2026-06-09T17:59:52.444755Z","end":"2026-06-09T17:59:52.715555Z","steps":["trace[1254166114] 'process raft request' (duration: 270.567583ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:52.715676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:52.375302Z","time spent":"340.286569ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:59:52.715979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.107658ms","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-06-09T17:59:52.716046Z","caller":"traceutil/trace.go:171","msg":"trace[920364214] 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:24776; }","duration":"156.2364ms","start":"2026-06-09T17:59:52.559792Z","end":"2026-06-09T17:59:52.716028Z","steps":["trace[920364214] 'agreement among raft nodes before linearized reading' (duration: 155.605269ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:52.716118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.003736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-06-09T17:59:52.716209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.874678ms","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-06-09T17:59:52.716153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.23661ms","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-06-09T17:59:52.716237Z","caller":"traceutil/trace.go:171","msg":"trace[1404284515] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24776; }","duration":"307.148559ms","start":"2026-06-09T17:59:52.409052Z","end":"2026-06-09T17:59:52.716201Z","steps":["trace[1404284515] 'agreement among raft nodes before linearized reading' (duration: 306.374864ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:52.71628Z","caller":"traceutil/trace.go:171","msg":"trace[1916880777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24776; }","duration":"308.411803ms","start":"2026-06-09T17:59:52.407848Z","end":"2026-06-09T17:59:52.71626Z","steps":["trace[1916880777] 'agreement among raft nodes before linearized reading' (duration: 307.589768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:52.716336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:52.409038Z","time spent":"307.279622ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":583,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-09T17:59:52.716343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:52.40782Z","time spent":"308.509045ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T17:59:52.716267Z","caller":"traceutil/trace.go:171","msg":"trace[1395351336] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24776; }","duration":"301.95202ms","start":"2026-06-09T17:59:52.4143Z","end":"2026-06-09T17:59:52.716252Z","steps":["trace[1395351336] 'agreement among raft nodes before linearized reading' (duration: 301.196367ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:52.716467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:52.414287Z","time spent":"302.143985ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T17:59:52.716505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.619267ms","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-06-09T17:59:52.716705Z","caller":"traceutil/trace.go:171","msg":"trace[885801781] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24776; }","duration":"308.800201ms","start":"2026-06-09T17:59:52.407791Z","end":"2026-06-09T17:59:52.716591Z","steps":["trace[885801781] 'agreement among raft nodes before linearized reading' (duration: 307.879454ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:52.716784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:52.407761Z","time spent":"309.006595ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T17:59:57.069859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.901151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:59:57.06998Z","caller":"traceutil/trace.go:171","msg":"trace[1620701351] transaction","detail":"{read_only:false; response_revision:24825; number_of_response:1; }","duration":"269.713178ms","start":"2026-06-09T17:59:56.800247Z","end":"2026-06-09T17:59:57.06996Z","steps":["trace[1620701351] 'process raft request' (duration: 99.606503ms)","trace[1620701351] 'compare' (duration: 169.561664ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:59:57.136599Z","caller":"traceutil/trace.go:171","msg":"trace[527438893] linearizableReadLoop","detail":"{readStateIndex:25650; appliedIndex:25648; }","duration":"334.021779ms","start":"2026-06-09T17:59:56.802546Z","end":"2026-06-09T17:59:57.136568Z","steps":["trace[527438893] 'read index received' (duration: 97.301319ms)","trace[527438893] 'applied index is now lower than readState.Index' (duration: 236.71915ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:59:57.136663Z","caller":"traceutil/trace.go:171","msg":"trace[1995414412] transaction","detail":"{read_only:false; response_revision:24827; number_of_response:1; }","duration":"332.748805ms","start":"2026-06-09T17:59:56.803864Z","end":"2026-06-09T17:59:57.136613Z","steps":["trace[1995414412] 'process raft request' (duration: 332.539581ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:57.136663Z","caller":"traceutil/trace.go:171","msg":"trace[1395590925] transaction","detail":"{read_only:false; response_revision:24826; number_of_response:1; }","duration":"335.865985ms","start":"2026-06-09T17:59:56.800728Z","end":"2026-06-09T17:59:57.136593Z","steps":["trace[1395590925] 'process raft request' (duration: 335.497178ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:57.136774Z","caller":"traceutil/trace.go:171","msg":"trace[1796118704] transaction","detail":"{read_only:false; response_revision:24828; number_of_response:1; }","duration":"332.481531ms","start":"2026-06-09T17:59:56.804261Z","end":"2026-06-09T17:59:57.136742Z","steps":["trace[1796118704] 'process raft request' (duration: 332.255686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:57.136825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:56.803856Z","time spent":"332.886178ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:59:57.136849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:56.800719Z","time spent":"336.037729ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:59:57.136917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.343095ms","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-06-09T17:59:57.136975Z","caller":"traceutil/trace.go:171","msg":"trace[657432093] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24828; }","duration":"334.431218ms","start":"2026-06-09T17:59:56.802527Z","end":"2026-06-09T17:59:57.136958Z","steps":["trace[657432093] 'agreement among raft nodes before linearized reading' (duration: 334.228313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:57.137021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:56.80252Z","time spent":"334.490529ms","remote":"127.0.0.1:44390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":381,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-06-09T17:59:57.136931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:56.80424Z","time spent":"332.609283ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:59:57.247703Z","caller":"traceutil/trace.go:171","msg":"trace[201672439] linearizableReadLoop","detail":"{readStateIndex:25653; appliedIndex:25652; }","duration":"110.95074ms","start":"2026-06-09T17:59:57.136711Z","end":"2026-06-09T17:59:57.247661Z","steps":["trace[201672439] 'read index received' (duration: 84.977966ms)","trace[201672439] 'applied index is now lower than readState.Index' (duration: 25.971434ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:57.247857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.787659ms","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-06-09T17:59:57.248002Z","caller":"traceutil/trace.go:171","msg":"trace[1761156348] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:24829; }","duration":"158.969003ms","start":"2026-06-09T17:59:57.089009Z","end":"2026-06-09T17:59:57.247978Z","steps":["trace[1761156348] 'agreement among raft nodes before linearized reading' (duration: 158.713698ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:59:57.248056Z","caller":"traceutil/trace.go:171","msg":"trace[668076393] transaction","detail":"{read_only:false; response_revision:24829; number_of_response:1; }","duration":"250.737257ms","start":"2026-06-09T17:59:56.997301Z","end":"2026-06-09T17:59:57.248038Z","steps":["trace[668076393] 'process raft request' (duration: 224.331445ms)","trace[668076393] 'compare' (duration: 25.80474ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:59:57.248399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.878167ms","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-06-09T17:59:57.248382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.785256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10362"} {"level":"info","ts":"2026-06-09T17:59:57.248493Z","caller":"traceutil/trace.go:171","msg":"trace[1965257677] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24829; }","duration":"415.001899ms","start":"2026-06-09T17:59:56.833468Z","end":"2026-06-09T17:59:57.24847Z","steps":["trace[1965257677] 'agreement among raft nodes before linearized reading' (duration: 414.752944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:57.248458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.166612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"warn","ts":"2026-06-09T17:59:57.248541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:56.833451Z","time spent":"415.081121ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-09T17:59:57.248571Z","caller":"traceutil/trace.go:171","msg":"trace[1022943326] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24829; }","duration":"304.02973ms","start":"2026-06-09T17:59:56.944496Z","end":"2026-06-09T17:59:57.248526Z","steps":["trace[1022943326] 'agreement among raft nodes before linearized reading' (duration: 303.659643ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:59:57.248621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:59:56.944468Z","time spent":"304.142002ms","remote":"127.0.0.1:44476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10386,"request content":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" "} {"level":"info","ts":"2026-06-09T17:59:57.248915Z","caller":"traceutil/trace.go:171","msg":"trace[855077868] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24829; }","duration":"294.346686ms","start":"2026-06-09T17:59:56.954224Z","end":"2026-06-09T17:59:57.248571Z","steps":["trace[855077868] 'agreement among raft nodes before linearized reading' (duration: 294.091631ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T18:00:03.591733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.185747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T18:00:03.591875Z","caller":"traceutil/trace.go:171","msg":"trace[1791020862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24927; }","duration":"220.35971ms","start":"2026-06-09T18:00:03.371454Z","end":"2026-06-09T18:00:03.591813Z","steps":["trace[1791020862] 'range keys from in-memory index tree' (duration: 220.055775ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T18:00:03.592325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.733425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T18:00:03.59243Z","caller":"traceutil/trace.go:171","msg":"trace[2127655708] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:24927; }","duration":"130.887238ms","start":"2026-06-09T18:00:03.461514Z","end":"2026-06-09T18:00:03.592402Z","steps":["trace[2127655708] 'count revisions from in-memory index tree' (duration: 130.669383ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:03.744705Z","caller":"traceutil/trace.go:171","msg":"trace[773452028] transaction","detail":"{read_only:false; response_revision:24928; number_of_response:1; }","duration":"120.317377ms","start":"2026-06-09T18:00:03.624358Z","end":"2026-06-09T18:00:03.744675Z","steps":["trace[773452028] 'process raft request' (duration: 120.086953ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:03.89228Z","caller":"traceutil/trace.go:171","msg":"trace[5232552] linearizableReadLoop","detail":"{readStateIndex:25754; appliedIndex:25753; }","duration":"123.879904ms","start":"2026-06-09T18:00:03.768376Z","end":"2026-06-09T18:00:03.892256Z","steps":["trace[5232552] 'read index received' (duration: 86.764999ms)","trace[5232552] 'applied index is now lower than readState.Index' (duration: 37.114135ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T18:00:03.8924Z","caller":"traceutil/trace.go:171","msg":"trace[186495923] transaction","detail":"{read_only:false; response_revision:24929; number_of_response:1; }","duration":"252.399438ms","start":"2026-06-09T18:00:03.639932Z","end":"2026-06-09T18:00:03.892331Z","steps":["trace[186495923] 'process raft request' (duration: 214.973027ms)","trace[186495923] 'compare' (duration: 37.227357ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T18:00:03.892502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.115169ms","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":"info","ts":"2026-06-09T18:00:03.892581Z","caller":"traceutil/trace.go:171","msg":"trace[62245637] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24929; }","duration":"124.248432ms","start":"2026-06-09T18:00:03.768314Z","end":"2026-06-09T18:00:03.892562Z","steps":["trace[62245637] 'agreement among raft nodes before linearized reading' (duration: 124.031308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T18:00:04.223612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.947111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T18:00:04.223743Z","caller":"traceutil/trace.go:171","msg":"trace[1892975973] transaction","detail":"{read_only:false; response_revision:24931; number_of_response:1; }","duration":"293.616712ms","start":"2026-06-09T18:00:03.930101Z","end":"2026-06-09T18:00:04.223717Z","steps":["trace[1892975973] 'process raft request' (duration: 123.474547ms)","trace[1892975973] 'compare' (duration: 169.740148ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T18:00:04.398443Z","caller":"traceutil/trace.go:171","msg":"trace[1699448823] transaction","detail":"{read_only:false; response_revision:24933; number_of_response:1; }","duration":"164.824863ms","start":"2026-06-09T18:00:04.233596Z","end":"2026-06-09T18:00:04.398421Z","steps":["trace[1699448823] 'process raft request' (duration: 124.026408ms)","trace[1699448823] 'compare' (duration: 40.698374ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T18:00:04.621295Z","caller":"traceutil/trace.go:171","msg":"trace[1262602454] transaction","detail":"{read_only:false; response_revision:24934; number_of_response:1; }","duration":"386.319125ms","start":"2026-06-09T18:00:04.234949Z","end":"2026-06-09T18:00:04.621269Z","steps":["trace[1262602454] 'process raft request' (duration: 294.196243ms)","trace[1262602454] 'compare' (duration: 91.698284ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T18:00:04.621443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T18:00:04.234933Z","time spent":"386.436677ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":870,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T18:00:04.621919Z","caller":"traceutil/trace.go:171","msg":"trace[1935987385] transaction","detail":"{read_only:false; response_revision:24935; number_of_response:1; }","duration":"309.005244ms","start":"2026-06-09T18:00:04.312873Z","end":"2026-06-09T18:00:04.621879Z","steps":["trace[1935987385] 'process raft request' (duration: 308.344592ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:04.622034Z","caller":"traceutil/trace.go:171","msg":"trace[1340362667] transaction","detail":"{read_only:false; response_revision:24936; number_of_response:1; }","duration":"306.63133ms","start":"2026-06-09T18:00:04.315386Z","end":"2026-06-09T18:00:04.622017Z","steps":["trace[1340362667] 'process raft request' (duration: 306.072259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T18:00:04.622043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T18:00:04.312851Z","time spent":"309.132146ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T18:00:04.622082Z","caller":"traceutil/trace.go:171","msg":"trace[1661216271] transaction","detail":"{read_only:false; response_revision:24937; number_of_response:1; }","duration":"305.840114ms","start":"2026-06-09T18:00:04.316212Z","end":"2026-06-09T18:00:04.622052Z","steps":["trace[1661216271] 'process raft request' (duration: 305.674461ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T18:00:04.62214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.683193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T18:00:04.622112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T18:00:04.315337Z","time spent":"306.738603ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T18:00:04.622007Z","caller":"traceutil/trace.go:171","msg":"trace[1714690536] linearizableReadLoop","detail":"{readStateIndex:25762; appliedIndex:25757; }","duration":"303.54901ms","start":"2026-06-09T18:00:04.318429Z","end":"2026-06-09T18:00:04.621978Z","steps":["trace[1714690536] 'read index received' (duration: 39.191095ms)","trace[1714690536] 'applied index is now lower than readState.Index' (duration: 264.356375ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T18:00:04.622214Z","caller":"traceutil/trace.go:171","msg":"trace[165442930] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:24937; }","duration":"303.791145ms","start":"2026-06-09T18:00:04.318404Z","end":"2026-06-09T18:00:04.622195Z","steps":["trace[165442930] 'agreement among raft nodes before linearized reading' (duration: 303.666413ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T18:00:04.622234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T18:00:04.316172Z","time spent":"305.988387ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T18:00:04.622282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T18:00:04.318389Z","time spent":"303.877207ms","remote":"127.0.0.1:47848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true "} {"level":"warn","ts":"2026-06-09T18:00:04.622509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.191872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra-registry.18b77b7d7bdf6344\" ","response":"range_response_count:1 size:722"} {"level":"warn","ts":"2026-06-09T18:00:04.622612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.592745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3237"} {"level":"info","ts":"2026-06-09T18:00:04.622659Z","caller":"traceutil/trace.go:171","msg":"trace[1035353895] range","detail":"{range_begin:/registry/events/openstack/container-infra-registry.18b77b7d7bdf6344; range_end:; response_count:1; response_revision:24937; }","duration":"214.269423ms","start":"2026-06-09T18:00:04.408272Z","end":"2026-06-09T18:00:04.622542Z","steps":["trace[1035353895] 'agreement among raft nodes before linearized reading' (duration: 214.11097ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:04.622716Z","caller":"traceutil/trace.go:171","msg":"trace[1748726297] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24937; }","duration":"290.752418ms","start":"2026-06-09T18:00:04.331933Z","end":"2026-06-09T18:00:04.622686Z","steps":["trace[1748726297] 'agreement among raft nodes before linearized reading' (duration: 290.428262ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:04.856142Z","caller":"traceutil/trace.go:171","msg":"trace[679096140] transaction","detail":"{read_only:false; response_revision:24939; number_of_response:1; }","duration":"176.549716ms","start":"2026-06-09T18:00:04.679545Z","end":"2026-06-09T18:00:04.856095Z","steps":["trace[679096140] 'process raft request' (duration: 172.178374ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:04.857235Z","caller":"traceutil/trace.go:171","msg":"trace[1010970797] linearizableReadLoop","detail":"{readStateIndex:25764; appliedIndex:25763; }","duration":"133.501388ms","start":"2026-06-09T18:00:04.72371Z","end":"2026-06-09T18:00:04.857211Z","steps":["trace[1010970797] 'read index received' (duration: 128.31548ms)","trace[1010970797] 'applied index is now lower than readState.Index' (duration: 5.183198ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T18:00:04.857418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.690112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra.18b77b7cdd1e56c4\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-06-09T18:00:04.857444Z","caller":"traceutil/trace.go:171","msg":"trace[1009845495] range","detail":"{range_begin:/registry/events/openstack/container-infra.18b77b7cdd1e56c4; range_end:; response_count:1; response_revision:24939; }","duration":"133.766664ms","start":"2026-06-09T18:00:04.72367Z","end":"2026-06-09T18:00:04.857437Z","steps":["trace[1009845495] 'agreement among raft nodes before linearized reading' (duration: 133.622221ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:08.442069Z","caller":"traceutil/trace.go:171","msg":"trace[592217978] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"119.075654ms","start":"2026-06-09T18:00:08.322965Z","end":"2026-06-09T18:00:08.442041Z","steps":["trace[592217978] 'process raft request' (duration: 106.003915ms)","trace[592217978] 'compare' (duration: 12.964177ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T18:00:14.68847Z","caller":"traceutil/trace.go:171","msg":"trace[289763796] linearizableReadLoop","detail":"{readStateIndex:25867; appliedIndex:25866; }","duration":"176.928963ms","start":"2026-06-09T18:00:14.511521Z","end":"2026-06-09T18:00:14.68845Z","steps":["trace[289763796] 'read index received' (duration: 176.718249ms)","trace[289763796] 'applied index is now lower than readState.Index' (duration: 209.834µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T18:00:14.688826Z","caller":"traceutil/trace.go:171","msg":"trace[845912233] transaction","detail":"{read_only:false; response_revision:25040; number_of_response:1; }","duration":"345.731153ms","start":"2026-06-09T18:00:14.343085Z","end":"2026-06-09T18:00:14.688816Z","steps":["trace[845912233] 'process raft request' (duration: 345.231014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T18:00:14.68896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T18:00:14.343061Z","time spent":"345.828845ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T18:00:14.689488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.972874ms","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-06-09T18:00:14.689568Z","caller":"traceutil/trace.go:171","msg":"trace[69456381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25040; }","duration":"178.090775ms","start":"2026-06-09T18:00:14.511468Z","end":"2026-06-09T18:00:14.689558Z","steps":["trace[69456381] 'agreement among raft nodes before linearized reading' (duration: 177.970673ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:14.855917Z","caller":"traceutil/trace.go:171","msg":"trace[2089572418] transaction","detail":"{read_only:false; response_revision:25041; number_of_response:1; }","duration":"162.53303ms","start":"2026-06-09T18:00:14.693354Z","end":"2026-06-09T18:00:14.855887Z","steps":["trace[2089572418] 'process raft request' (duration: 93.019728ms)","trace[2089572418] 'compare' (duration: 69.37978ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T18:00:15.026611Z","caller":"traceutil/trace.go:171","msg":"trace[649259713] transaction","detail":"{read_only:false; response_revision:25045; number_of_response:1; }","duration":"101.99181ms","start":"2026-06-09T18:00:14.924597Z","end":"2026-06-09T18:00:15.026589Z","steps":["trace[649259713] 'process raft request' (duration: 58.975442ms)","trace[649259713] 'compare' (duration: 42.910186ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T18:00:19.192959Z","caller":"traceutil/trace.go:171","msg":"trace[1316605855] transaction","detail":"{read_only:false; response_revision:25096; number_of_response:1; }","duration":"214.987987ms","start":"2026-06-09T18:00:18.977948Z","end":"2026-06-09T18:00:19.192936Z","steps":["trace[1316605855] 'process raft request' (duration: 214.821334ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:19.193332Z","caller":"traceutil/trace.go:171","msg":"trace[239132937] transaction","detail":"{read_only:false; response_revision:25097; number_of_response:1; }","duration":"152.259885ms","start":"2026-06-09T18:00:19.041061Z","end":"2026-06-09T18:00:19.19332Z","steps":["trace[239132937] 'process raft request' (duration: 151.838426ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:19.19335Z","caller":"traceutil/trace.go:171","msg":"trace[613304684] linearizableReadLoop","detail":"{readStateIndex:25924; appliedIndex:25922; }","duration":"179.458533ms","start":"2026-06-09T18:00:19.013861Z","end":"2026-06-09T18:00:19.193319Z","steps":["trace[613304684] 'read index received' (duration: 29.175535ms)","trace[613304684] 'applied index is now lower than readState.Index' (duration: 150.280408ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T18:00:19.193566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.648786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T18:00:19.193668Z","caller":"traceutil/trace.go:171","msg":"trace[2043731646] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:25097; }","duration":"179.82776ms","start":"2026-06-09T18:00:19.013816Z","end":"2026-06-09T18:00:19.193644Z","steps":["trace[2043731646] 'agreement among raft nodes before linearized reading' (duration: 179.606466ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T18:00:22.112562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.150874ms","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":"info","ts":"2026-06-09T18:00:22.112716Z","caller":"traceutil/trace.go:171","msg":"trace[1631400901] 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:25119; }","duration":"161.348808ms","start":"2026-06-09T18:00:21.951326Z","end":"2026-06-09T18:00:22.112675Z","steps":["trace[1631400901] 'count revisions from in-memory index tree' (duration: 161.028521ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:22.113576Z","caller":"traceutil/trace.go:171","msg":"trace[799989412] transaction","detail":"{read_only:false; response_revision:25120; number_of_response:1; }","duration":"154.174311ms","start":"2026-06-09T18:00:21.959381Z","end":"2026-06-09T18:00:22.113556Z","steps":["trace[799989412] 'process raft request' (duration: 112.02822ms)","trace[799989412] 'compare' (duration: 41.821885ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T18:00:35.571179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.052746ms","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-06-09T18:00:35.571277Z","caller":"traceutil/trace.go:171","msg":"trace[330680343] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25240; }","duration":"137.197218ms","start":"2026-06-09T18:00:35.434052Z","end":"2026-06-09T18:00:35.571249Z","steps":["trace[330680343] 'range keys from in-memory index tree' (duration: 136.861093ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:35.702133Z","caller":"traceutil/trace.go:171","msg":"trace[375022998] transaction","detail":"{read_only:false; response_revision:25241; number_of_response:1; }","duration":"126.516435ms","start":"2026-06-09T18:00:35.575584Z","end":"2026-06-09T18:00:35.702101Z","steps":["trace[375022998] 'process raft request' (duration: 126.310171ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:35.877057Z","caller":"traceutil/trace.go:171","msg":"trace[1874267786] transaction","detail":"{read_only:false; response_revision:25242; number_of_response:1; }","duration":"178.34994ms","start":"2026-06-09T18:00:35.698666Z","end":"2026-06-09T18:00:35.877016Z","steps":["trace[1874267786] 'process raft request' (duration: 152.488489ms)","trace[1874267786] 'compare' (duration: 25.647247ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T18:00:36.104868Z","caller":"traceutil/trace.go:171","msg":"trace[333475002] transaction","detail":"{read_only:false; response_revision:25243; number_of_response:1; }","duration":"154.778943ms","start":"2026-06-09T18:00:35.950043Z","end":"2026-06-09T18:00:36.104822Z","steps":["trace[333475002] 'process raft request' (duration: 133.56336ms)","trace[333475002] 'compare' (duration: 20.627912ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T18:00:36.310535Z","caller":"traceutil/trace.go:171","msg":"trace[1628753218] transaction","detail":"{read_only:false; response_revision:25244; number_of_response:1; }","duration":"150.095713ms","start":"2026-06-09T18:00:36.160397Z","end":"2026-06-09T18:00:36.310493Z","steps":["trace[1628753218] 'process raft request' (duration: 89.212195ms)","trace[1628753218] 'compare' (duration: 60.577893ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T18:00:36.553337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.360495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T18:00:36.553436Z","caller":"traceutil/trace.go:171","msg":"trace[1074505670] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:25244; }","duration":"133.521008ms","start":"2026-06-09T18:00:36.419895Z","end":"2026-06-09T18:00:36.553416Z","steps":["trace[1074505670] 'count revisions from in-memory index tree' (duration: 133.270864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T18:00:43.106974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.806952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T18:00:43.107189Z","caller":"traceutil/trace.go:171","msg":"trace[198987793] linearizableReadLoop","detail":"{readStateIndex:26122; appliedIndex:26121; }","duration":"199.36597ms","start":"2026-06-09T18:00:42.907794Z","end":"2026-06-09T18:00:43.107159Z","steps":["trace[198987793] 'read index received' (duration: 11.768823ms)","trace[198987793] 'applied index is now lower than readState.Index' (duration: 187.594327ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T18:00:43.107098Z","caller":"traceutil/trace.go:171","msg":"trace[1226310945] transaction","detail":"{read_only:false; response_revision:25289; number_of_response:1; }","duration":"255.631409ms","start":"2026-06-09T18:00:42.851441Z","end":"2026-06-09T18:00:43.107072Z","steps":["trace[1226310945] 'process raft request' (duration: 68.278587ms)","trace[1226310945] 'compare' (duration: 186.67479ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T18:00:43.1074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.585424ms","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-06-09T18:00:43.107496Z","caller":"traceutil/trace.go:171","msg":"trace[1767257944] 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:25290; }","duration":"199.748727ms","start":"2026-06-09T18:00:42.907726Z","end":"2026-06-09T18:00:43.107474Z","steps":["trace[1767257944] 'agreement among raft nodes before linearized reading' (duration: 199.539873ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:43.318758Z","caller":"traceutil/trace.go:171","msg":"trace[485850365] transaction","detail":"{read_only:false; response_revision:25292; number_of_response:1; }","duration":"258.054306ms","start":"2026-06-09T18:00:43.060669Z","end":"2026-06-09T18:00:43.318723Z","steps":["trace[485850365] 'process raft request' (duration: 257.968584ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:43.318941Z","caller":"traceutil/trace.go:171","msg":"trace[904746229] linearizableReadLoop","detail":"{readStateIndex:26125; appliedIndex:26123; }","duration":"211.650263ms","start":"2026-06-09T18:00:43.107262Z","end":"2026-06-09T18:00:43.318912Z","steps":["trace[904746229] 'read index received' (duration: 124.231982ms)","trace[904746229] 'applied index is now lower than readState.Index' (duration: 87.416341ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T18:00:43.319154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.287663ms","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":"warn","ts":"2026-06-09T18:00:43.319199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.495523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18368"} {"level":"info","ts":"2026-06-09T18:00:43.319201Z","caller":"traceutil/trace.go:171","msg":"trace[978424944] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25293; }","duration":"205.378594ms","start":"2026-06-09T18:00:43.113808Z","end":"2026-06-09T18:00:43.319187Z","steps":["trace[978424944] 'agreement among raft nodes before linearized reading' (duration: 205.289743ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:43.319239Z","caller":"traceutil/trace.go:171","msg":"trace[1728479980] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25293; }","duration":"381.595165ms","start":"2026-06-09T18:00:42.937632Z","end":"2026-06-09T18:00:43.319228Z","steps":["trace[1728479980] 'agreement among raft nodes before linearized reading' (duration: 381.425862ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T18:00:43.319275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T18:00:42.93761Z","time spent":"381.656096ms","remote":"127.0.0.1:44464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18392,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-09T18:00:43.319406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.751477ms","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-06-09T18:00:43.319528Z","caller":"traceutil/trace.go:171","msg":"trace[435137409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25293; }","duration":"149.93735ms","start":"2026-06-09T18:00:43.16956Z","end":"2026-06-09T18:00:43.319498Z","steps":["trace[435137409] 'agreement among raft nodes before linearized reading' (duration: 149.587814ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:43.320192Z","caller":"traceutil/trace.go:171","msg":"trace[1899316274] transaction","detail":"{read_only:false; response_revision:25291; number_of_response:1; }","duration":"266.618449ms","start":"2026-06-09T18:00:43.053466Z","end":"2026-06-09T18:00:43.320084Z","steps":["trace[1899316274] 'process raft request' (duration: 178.087286ms)","trace[1899316274] 'compare' (duration: 86.950243ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T18:00:43.551763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.037569ms","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-06-09T18:00:43.55188Z","caller":"traceutil/trace.go:171","msg":"trace[1062367754] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25295; }","duration":"113.212092ms","start":"2026-06-09T18:00:43.438643Z","end":"2026-06-09T18:00:43.551855Z","steps":["trace[1062367754] 'range keys from in-memory index tree' (duration: 112.773864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T18:00:43.552041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.402017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-09T18:00:43.552192Z","caller":"traceutil/trace.go:171","msg":"trace[965229105] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25295; }","duration":"112.608901ms","start":"2026-06-09T18:00:43.439547Z","end":"2026-06-09T18:00:43.552156Z","steps":["trace[965229105] 'range keys from in-memory index tree' (duration: 112.141462ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T18:00:43.552248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.155803ms","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-06-09T18:00:43.552378Z","caller":"traceutil/trace.go:171","msg":"trace[1388793406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25295; }","duration":"121.356177ms","start":"2026-06-09T18:00:43.430994Z","end":"2026-06-09T18:00:43.552351Z","steps":["trace[1388793406] 'range keys from in-memory index tree' (duration: 120.666743ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:43.694496Z","caller":"traceutil/trace.go:171","msg":"trace[1927488738] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"136.877743ms","start":"2026-06-09T18:00:43.557588Z","end":"2026-06-09T18:00:43.694466Z","steps":["trace[1927488738] 'process raft request' (duration: 136.633618ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T18:00:43.884683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.330952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T18:00:43.885129Z","caller":"traceutil/trace.go:171","msg":"trace[912328636] transaction","detail":"{read_only:false; response_revision:25297; number_of_response:1; }","duration":"327.121939ms","start":"2026-06-09T18:00:43.557803Z","end":"2026-06-09T18:00:43.884925Z","steps":["trace[912328636] 'process raft request' (duration: 201.357148ms)","trace[912328636] 'compare' (duration: 125.033087ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T18:00:43.885393Z","caller":"traceutil/trace.go:171","msg":"trace[1400287516] transaction","detail":"{read_only:false; response_revision:25298; number_of_response:1; }","duration":"326.586598ms","start":"2026-06-09T18:00:43.558721Z","end":"2026-06-09T18:00:43.885307Z","steps":["trace[1400287516] 'process raft request' (duration: 326.11779ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:43.885423Z","caller":"traceutil/trace.go:171","msg":"trace[423680496] linearizableReadLoop","detail":"{readStateIndex:26131; appliedIndex:26128; }","duration":"283.54307ms","start":"2026-06-09T18:00:43.601851Z","end":"2026-06-09T18:00:43.885394Z","steps":["trace[423680496] 'read index received' (duration: 92.489148ms)","trace[423680496] 'applied index is now lower than readState.Index' (duration: 191.051642ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T18:00:43.88558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T18:00:43.557792Z","time spent":"327.424875ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T18:00:43.885599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T18:00:43.558702Z","time spent":"326.795912ms","remote":"127.0.0.1:44556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T18:00:43.885685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.784584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T18:00:43.88578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.473508ms","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":"info","ts":"2026-06-09T18:00:43.885923Z","caller":"traceutil/trace.go:171","msg":"trace[1813102589] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:25298; }","duration":"192.628531ms","start":"2026-06-09T18:00:43.693273Z","end":"2026-06-09T18:00:43.885901Z","steps":["trace[1813102589] 'agreement among raft nodes before linearized reading' (duration: 192.395167ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:43.885826Z","caller":"traceutil/trace.go:171","msg":"trace[419512675] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25298; }","duration":"283.987458ms","start":"2026-06-09T18:00:43.601808Z","end":"2026-06-09T18:00:43.885796Z","steps":["trace[419512675] 'agreement among raft nodes before linearized reading' (duration: 283.708333ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:02:12.131227Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23229} {"level":"info","ts":"2026-06-09T18:02:12.285744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23229,"took":"146.639718ms","hash":3500522727,"current-db-size-bytes":60932096,"current-db-size":"61 MB","current-db-size-in-use-bytes":36417536,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-06-09T18:02:12.285813Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3500522727,"revision":23229,"compact-revision":20058} {"level":"info","ts":"2026-06-09T18:02:26.276614Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a16.wal"} {"level":"info","ts":"2026-06-09T18:07:12.136101Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26170} {"level":"info","ts":"2026-06-09T18:07:12.278123Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26170,"took":"138.061529ms","hash":4252028181,"current-db-size-bytes":60932096,"current-db-size":"61 MB","current-db-size-in-use-bytes":33845248,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-09T18:07:12.278201Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4252028181,"revision":26170,"compact-revision":23229} {"level":"info","ts":"2026-06-09T18:07:43.93744Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9abf795dae4ebc2c","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T18:07:43.943286Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-09T18:07:43.943382Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-09T18:12:12.143227Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28838} {"level":"info","ts":"2026-06-09T18:12:12.287313Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28838,"took":"138.68234ms","hash":2387758684,"current-db-size-bytes":60932096,"current-db-size":"61 MB","current-db-size-in-use-bytes":30232576,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-09T18:12:12.287389Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2387758684,"revision":28838,"compact-revision":26170} {"level":"info","ts":"2026-06-09T18:16:59.458279Z","caller":"traceutil/trace.go:171","msg":"trace[1187892894] transaction","detail":"{read_only:false; response_revision:33229; number_of_response:1; }","duration":"100.241606ms","start":"2026-06-09T18:16:59.357994Z","end":"2026-06-09T18:16:59.458236Z","steps":["trace[1187892894] 'process raft request' (duration: 99.993851ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:17:12.149085Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31056} {"level":"info","ts":"2026-06-09T18:17:12.259068Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31056,"took":"106.102026ms","hash":1408049376,"current-db-size-bytes":60932096,"current-db-size":"61 MB","current-db-size-in-use-bytes":31219712,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-09T18:17:12.25913Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1408049376,"revision":31056,"compact-revision":28838} {"level":"info","ts":"2026-06-09T18:22:12.158007Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33321} {"level":"info","ts":"2026-06-09T18:22:12.305346Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33321,"took":"142.500014ms","hash":4227442754,"current-db-size-bytes":60932096,"current-db-size":"61 MB","current-db-size-in-use-bytes":32043008,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-09T18:22:12.305453Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4227442754,"revision":33321,"compact-revision":31056} {"level":"info","ts":"2026-06-09T18:27:12.166153Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35611} {"level":"info","ts":"2026-06-09T18:27:12.321646Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35611,"took":"149.976584ms","hash":1068977753,"current-db-size-bytes":62078976,"current-db-size":"62 MB","current-db-size-in-use-bytes":31092736,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-09T18:27:12.321749Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1068977753,"revision":35611,"compact-revision":33321} {"level":"info","ts":"2026-06-09T18:29:14.508634Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9abf795dae4ebc2c","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T18:29:14.519179Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-09T18:29:14.519417Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}