{"level":"warn","ts":"2026-04-08T00:33:50.42479Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T00:33:50.424984Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-04-08T00:33:50.425151Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T00:33:50.425181Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-08T00:33:50.425224Z","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-04-08T00:33:50.426436Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-04-08T00:33:50.426563Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-04-08T00:33:50.431007Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.074355ms"} {"level":"info","ts":"2026-04-08T00:33:50.437841Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-04-08T00:33:50.438049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T00:33:50.438101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-04-08T00:33:50.438131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T00:33:50.438147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-04-08T00:33:50.43823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-04-08T00:33:50.45004Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T00:33:50.45184Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T00:33:50.45337Z","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-04-08T00:33:50.456501Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T00:33:50.456829Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T00:33:50.457198Z","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-04-08T00:33:50.457314Z","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-04-08T00:33:50.45733Z","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-04-08T00:33:50.458782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-04-08T00:33:50.459986Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-08T00:33:50.461397Z","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-04-08T00:33:50.461795Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-08T00:33:50.461874Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-08T00:33:50.461941Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T00:33:50.461997Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T00:33:50.939461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T00:33:50.939559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T00:33:50.939589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-04-08T00:33:50.939623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-04-08T00:33:50.939634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-08T00:33:50.93965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-04-08T00:33:50.93967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-08T00:33:51.022396Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:33:51.055844Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T00:33:51.055886Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:33:51.055848Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:33:51.056292Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T00:33:51.056313Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T00:33:51.057623Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-04-08T00:33:51.059157Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T00:33:51.065727Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:33:51.065864Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:33:51.065907Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:33:55.169982Z","caller":"traceutil/trace.go:171","msg":"trace[1759035295] linearizableReadLoop","detail":"{readStateIndex:225; appliedIndex:224; }","duration":"148.010146ms","start":"2026-04-08T00:33:55.021952Z","end":"2026-04-08T00:33:55.169963Z","steps":["trace[1759035295] 'read index received' (duration: 147.859053ms)","trace[1759035295] 'applied index is now lower than readState.Index' (duration: 150.463µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:33:55.17002Z","caller":"traceutil/trace.go:171","msg":"trace[1648142039] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"190.921904ms","start":"2026-04-08T00:33:54.979077Z","end":"2026-04-08T00:33:55.169999Z","steps":["trace[1648142039] 'process raft request' (duration: 190.777691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:33:55.170215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.241381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3496"} {"level":"info","ts":"2026-04-08T00:33:55.170347Z","caller":"traceutil/trace.go:171","msg":"trace[1904852141] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:219; }","duration":"148.431424ms","start":"2026-04-08T00:33:55.021896Z","end":"2026-04-08T00:33:55.170327Z","steps":["trace[1904852141] 'agreement among raft nodes before linearized reading' (duration: 148.19429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:08.94611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.385225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-08T00:34:08.946119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.040525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:08.946222Z","caller":"traceutil/trace.go:171","msg":"trace[1639204703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:401; }","duration":"177.574467ms","start":"2026-04-08T00:34:08.768626Z","end":"2026-04-08T00:34:08.946201Z","steps":["trace[1639204703] 'range keys from in-memory index tree' (duration: 177.232912ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:08.946245Z","caller":"traceutil/trace.go:171","msg":"trace[1262117654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"313.225508ms","start":"2026-04-08T00:34:08.632992Z","end":"2026-04-08T00:34:08.946218Z","steps":["trace[1262117654] 'range keys from in-memory index tree' (duration: 312.895142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:08.9463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:08.632968Z","time spent":"313.322821ms","remote":"127.0.0.1:48890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T00:34:20.886252Z","caller":"traceutil/trace.go:171","msg":"trace[1495682267] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"107.784393ms","start":"2026-04-08T00:34:20.778443Z","end":"2026-04-08T00:34:20.886227Z","steps":["trace[1495682267] 'read index received' (duration: 107.574469ms)","trace[1495682267] 'applied index is now lower than readState.Index' (duration: 208.804µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:34:20.886393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.936725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:20.886389Z","caller":"traceutil/trace.go:171","msg":"trace[1770453709] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"201.526942ms","start":"2026-04-08T00:34:20.68479Z","end":"2026-04-08T00:34:20.886317Z","steps":["trace[1770453709] 'process raft request' (duration: 201.286758ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:20.88643Z","caller":"traceutil/trace.go:171","msg":"trace[414935863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:465; }","duration":"108.042627ms","start":"2026-04-08T00:34:20.778374Z","end":"2026-04-08T00:34:20.886417Z","steps":["trace[414935863] 'agreement among raft nodes before linearized reading' (duration: 107.961655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:21.78776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.797789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:34:21.787896Z","caller":"traceutil/trace.go:171","msg":"trace[1255205565] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"160.794988ms","start":"2026-04-08T00:34:21.627083Z","end":"2026-04-08T00:34:21.787878Z","steps":["trace[1255205565] 'compare' (duration: 154.602065ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:21.788477Z","caller":"traceutil/trace.go:171","msg":"trace[294430739] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"155.541802ms","start":"2026-04-08T00:34:21.632922Z","end":"2026-04-08T00:34:21.788464Z","steps":["trace[294430739] 'process raft request' (duration: 155.347348ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:21.794316Z","caller":"traceutil/trace.go:171","msg":"trace[1570428937] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"105.673755ms","start":"2026-04-08T00:34:21.688554Z","end":"2026-04-08T00:34:21.794228Z","steps":["trace[1570428937] 'process raft request' (duration: 105.457321ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:21.993124Z","caller":"traceutil/trace.go:171","msg":"trace[297287478] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"149.700188ms","start":"2026-04-08T00:34:21.84337Z","end":"2026-04-08T00:34:21.99307Z","steps":["trace[297287478] 'process raft request' (duration: 132.878315ms)","trace[297287478] 'compare' (duration: 16.67357ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:21.993592Z","caller":"traceutil/trace.go:171","msg":"trace[854841431] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"137.791822ms","start":"2026-04-08T00:34:21.855774Z","end":"2026-04-08T00:34:21.993566Z","steps":["trace[854841431] 'process raft request' (duration: 137.521928ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:21.993761Z","caller":"traceutil/trace.go:171","msg":"trace[1948214603] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"114.651176ms","start":"2026-04-08T00:34:21.879073Z","end":"2026-04-08T00:34:21.993725Z","steps":["trace[1948214603] 'process raft request' (duration: 114.462683ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:27.985131Z","caller":"traceutil/trace.go:171","msg":"trace[787026334] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"121.207213ms","start":"2026-04-08T00:34:27.863896Z","end":"2026-04-08T00:34:27.985104Z","steps":["trace[787026334] 'read index received' (duration: 120.4584ms)","trace[787026334] 'applied index is now lower than readState.Index' (duration: 747.863µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:27.985197Z","caller":"traceutil/trace.go:171","msg":"trace[635255155] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"161.243715ms","start":"2026-04-08T00:34:27.823916Z","end":"2026-04-08T00:34:27.98516Z","steps":["trace[635255155] 'process raft request' (duration: 160.663375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:27.985263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.349986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:27.985296Z","caller":"traceutil/trace.go:171","msg":"trace[380040339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"121.432608ms","start":"2026-04-08T00:34:27.863854Z","end":"2026-04-08T00:34:27.985286Z","steps":["trace[380040339] 'agreement among raft nodes before linearized reading' (duration: 121.354496ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:28.308641Z","caller":"traceutil/trace.go:171","msg":"trace[1722166082] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"267.605843ms","start":"2026-04-08T00:34:28.041014Z","end":"2026-04-08T00:34:28.30862Z","steps":["trace[1722166082] 'process raft request' (duration: 215.606235ms)","trace[1722166082] 'compare' (duration: 51.767974ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:29.101136Z","caller":"traceutil/trace.go:171","msg":"trace[2121431602] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"773.953686ms","start":"2026-04-08T00:34:28.327155Z","end":"2026-04-08T00:34:29.101109Z","steps":["trace[2121431602] 'process raft request' (duration: 676.111204ms)","trace[2121431602] 'compare' (duration: 96.898905ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:34:29.1013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:28.327135Z","time spent":"774.083599ms","remote":"127.0.0.1:48800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:34:29.101618Z","caller":"traceutil/trace.go:171","msg":"trace[808237624] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:597; }","duration":"182.03675ms","start":"2026-04-08T00:34:28.919556Z","end":"2026-04-08T00:34:29.101593Z","steps":["trace[808237624] 'read index received' (duration: 83.687918ms)","trace[808237624] 'applied index is now lower than readState.Index' (duration: 98.347802ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:29.101734Z","caller":"traceutil/trace.go:171","msg":"trace[965357324] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"372.254298ms","start":"2026-04-08T00:34:28.729436Z","end":"2026-04-08T00:34:29.10169Z","steps":["trace[965357324] 'process raft request' (duration: 371.415372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.101798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.264246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:34:29.101891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.327815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-08T00:34:29.101916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:34:28.729384Z","time spent":"372.440281ms","remote":"127.0.0.1:48728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:34:29.101984Z","caller":"traceutil/trace.go:171","msg":"trace[2120984013] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"182.424187ms","start":"2026-04-08T00:34:28.919531Z","end":"2026-04-08T00:34:29.101955Z","steps":["trace[2120984013] 'agreement among raft nodes before linearized reading' (duration: 182.185952ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:29.102042Z","caller":"traceutil/trace.go:171","msg":"trace[2114630237] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:579; }","duration":"115.376388ms","start":"2026-04-08T00:34:28.9865Z","end":"2026-04-08T00:34:29.101876Z","steps":["trace[2114630237] 'agreement among raft nodes before linearized reading' (duration: 115.240886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.10229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.166335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:29.102334Z","caller":"traceutil/trace.go:171","msg":"trace[791056965] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:579; }","duration":"115.235966ms","start":"2026-04-08T00:34:28.987084Z","end":"2026-04-08T00:34:29.10232Z","steps":["trace[791056965] 'agreement among raft nodes before linearized reading' (duration: 115.157955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:29.102446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.778736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:34:29.102473Z","caller":"traceutil/trace.go:171","msg":"trace[909463754] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:579; }","duration":"115.841906ms","start":"2026-04-08T00:34:28.986623Z","end":"2026-04-08T00:34:29.102464Z","steps":["trace[909463754] 'agreement among raft nodes before linearized reading' (duration: 115.792176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:44.624175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.799512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-blpnh\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-08T00:34:44.624276Z","caller":"traceutil/trace.go:171","msg":"trace[1697281825] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-blpnh; range_end:; response_count:1; response_revision:736; }","duration":"248.958685ms","start":"2026-04-08T00:34:44.375295Z","end":"2026-04-08T00:34:44.624254Z","steps":["trace[1697281825] 'range keys from in-memory index tree' (duration: 248.548348ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:12.036855Z","caller":"traceutil/trace.go:171","msg":"trace[553270072] linearizableReadLoop","detail":"{readStateIndex:1164; appliedIndex:1163; }","duration":"257.465139ms","start":"2026-04-08T00:35:11.779362Z","end":"2026-04-08T00:35:12.036827Z","steps":["trace[553270072] 'read index received' (duration: 182.475637ms)","trace[553270072] 'applied index is now lower than readState.Index' (duration: 74.988372ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:35:12.036889Z","caller":"traceutil/trace.go:171","msg":"trace[198548584] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"303.195304ms","start":"2026-04-08T00:35:11.733661Z","end":"2026-04-08T00:35:12.036857Z","steps":["trace[198548584] 'process raft request' (duration: 228.233952ms)","trace[198548584] 'compare' (duration: 74.607086ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:35:12.036985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.618272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:35:12.037028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:35:11.733608Z","time spent":"303.349107ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:35:12.03704Z","caller":"traceutil/trace.go:171","msg":"trace[1906553247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1133; }","duration":"257.744784ms","start":"2026-04-08T00:35:11.779279Z","end":"2026-04-08T00:35:12.037024Z","steps":["trace[1906553247] 'agreement among raft nodes before linearized reading' (duration: 257.639402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:58.270373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.736297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-04-08T00:35:58.270402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.66069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-08T00:35:58.270421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.569562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-08T00:35:58.270463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.913879ms","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-04-08T00:35:58.270494Z","caller":"traceutil/trace.go:171","msg":"trace[758749905] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1790; }","duration":"489.940301ms","start":"2026-04-08T00:35:57.780533Z","end":"2026-04-08T00:35:58.270473Z","steps":["trace[758749905] 'range keys from in-memory index tree' (duration: 489.498393ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:58.270504Z","caller":"traceutil/trace.go:171","msg":"trace[1922745000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1790; }","duration":"587.834293ms","start":"2026-04-08T00:35:57.682651Z","end":"2026-04-08T00:35:58.270485Z","steps":["trace[1922745000] 'range keys from in-memory index tree' (duration: 587.390484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:58.270402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.381165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-08T00:35:58.270452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.532941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cd64ea04-f38a-45d0-8af1-3763d1371283\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-08T00:35:58.272393Z","caller":"traceutil/trace.go:171","msg":"trace[1592826479] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1790; }","duration":"452.406251ms","start":"2026-04-08T00:35:57.819943Z","end":"2026-04-08T00:35:58.272349Z","steps":["trace[1592826479] 'range keys from in-memory index tree' (duration: 450.15216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:58.272449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:35:57.819919Z","time spent":"452.516934ms","remote":"127.0.0.1:33824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-08T00:35:58.272482Z","caller":"traceutil/trace.go:171","msg":"trace[479433041] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cd64ea04-f38a-45d0-8af1-3763d1371283; range_end:; response_count:1; response_revision:1790; }","duration":"500.601329ms","start":"2026-04-08T00:35:57.771833Z","end":"2026-04-08T00:35:58.272435Z","steps":["trace[479433041] 'range keys from in-memory index tree' (duration: 498.241965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:58.27284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:35:57.771787Z","time spent":"500.806713ms","remote":"127.0.0.1:48800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cd64ea04-f38a-45d0-8af1-3763d1371283\" "} {"level":"info","ts":"2026-04-08T00:35:58.27147Z","caller":"traceutil/trace.go:171","msg":"trace[872650313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1790; }","duration":"584.740455ms","start":"2026-04-08T00:35:57.685757Z","end":"2026-04-08T00:35:58.270497Z","steps":["trace[872650313] 'range keys from in-memory index tree' (duration: 584.225486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:58.273059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:35:57.685737Z","time spent":"587.308844ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T00:35:58.271611Z","caller":"traceutil/trace.go:171","msg":"trace[1284267108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1790; }","duration":"569.99207ms","start":"2026-04-08T00:35:57.700511Z","end":"2026-04-08T00:35:58.270503Z","steps":["trace[1284267108] 'range keys from in-memory index tree' (duration: 569.763516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:58.273561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:35:57.7005Z","time spent":"573.028746ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T00:35:58.272261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:35:57.682617Z","time spent":"587.927564ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T00:35:58.27054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:35:57.780516Z","time spent":"490.013422ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-08T00:35:58.292485Z","caller":"traceutil/trace.go:171","msg":"trace[123792502] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"398.168473ms","start":"2026-04-08T00:35:57.894295Z","end":"2026-04-08T00:35:58.292464Z","steps":["trace[123792502] 'process raft request' (duration: 397.963189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:58.292646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:35:57.894262Z","time spent":"398.284935ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:35:58.425936Z","caller":"traceutil/trace.go:171","msg":"trace[948964992] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"145.661941ms","start":"2026-04-08T00:35:58.280241Z","end":"2026-04-08T00:35:58.425903Z","steps":["trace[948964992] 'process raft request' (duration: 145.437797ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:58.426235Z","caller":"traceutil/trace.go:171","msg":"trace[1321072667] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"145.755243ms","start":"2026-04-08T00:35:58.280426Z","end":"2026-04-08T00:35:58.426182Z","steps":["trace[1321072667] 'process raft request' (duration: 145.419737ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:58.426388Z","caller":"traceutil/trace.go:171","msg":"trace[2121155052] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"144.715333ms","start":"2026-04-08T00:35:58.281641Z","end":"2026-04-08T00:35:58.426356Z","steps":["trace[2121155052] 'process raft request' (duration: 144.52018ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:58.426521Z","caller":"traceutil/trace.go:171","msg":"trace[168046302] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"143.831697ms","start":"2026-04-08T00:35:58.28266Z","end":"2026-04-08T00:35:58.426492Z","steps":["trace[168046302] 'process raft request' (duration: 143.595823ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:58.42668Z","caller":"traceutil/trace.go:171","msg":"trace[1136949762] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"146.12007ms","start":"2026-04-08T00:35:58.280512Z","end":"2026-04-08T00:35:58.426632Z","steps":["trace[1136949762] 'process raft request' (duration: 145.55339ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:58.568464Z","caller":"traceutil/trace.go:171","msg":"trace[1214599186] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"129.527521ms","start":"2026-04-08T00:35:58.4389Z","end":"2026-04-08T00:35:58.568428Z","steps":["trace[1214599186] 'process raft request' (duration: 75.849681ms)","trace[1214599186] 'compare' (duration: 53.536768ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:35:58.99531Z","caller":"traceutil/trace.go:171","msg":"trace[1515552103] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"186.742817ms","start":"2026-04-08T00:35:58.808526Z","end":"2026-04-08T00:35:58.995269Z","steps":["trace[1515552103] 'process raft request' (duration: 186.441971ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:59.011053Z","caller":"traceutil/trace.go:171","msg":"trace[389987005] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"175.65292ms","start":"2026-04-08T00:35:58.835379Z","end":"2026-04-08T00:35:59.011032Z","steps":["trace[389987005] 'process raft request' (duration: 175.473717ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:44.845281Z","caller":"traceutil/trace.go:171","msg":"trace[367127702] linearizableReadLoop","detail":"{readStateIndex:2175; appliedIndex:2174; }","duration":"182.967106ms","start":"2026-04-08T00:36:44.662289Z","end":"2026-04-08T00:36:44.845256Z","steps":["trace[367127702] 'read index received' (duration: 182.850243ms)","trace[367127702] 'applied index is now lower than readState.Index' (duration: 115.843µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:44.845391Z","caller":"traceutil/trace.go:171","msg":"trace[2033748352] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"371.486074ms","start":"2026-04-08T00:36:44.473879Z","end":"2026-04-08T00:36:44.845365Z","steps":["trace[2033748352] 'process raft request' (duration: 371.21453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:44.845539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:44.473851Z","time spent":"371.595328ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:36:44.845546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.851701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-04-08T00:36:44.845598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.307072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-08T00:36:44.845658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.300398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T00:36:44.845682Z","caller":"traceutil/trace.go:171","msg":"trace[1730688429] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2098; }","duration":"127.027065ms","start":"2026-04-08T00:36:44.718628Z","end":"2026-04-08T00:36:44.845655Z","steps":["trace[1730688429] 'agreement among raft nodes before linearized reading' (duration: 126.795241ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:44.845709Z","caller":"traceutil/trace.go:171","msg":"trace[1561934014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2098; }","duration":"122.390559ms","start":"2026-04-08T00:36:44.723303Z","end":"2026-04-08T00:36:44.845694Z","steps":["trace[1561934014] 'agreement among raft nodes before linearized reading' (duration: 122.277237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:44.8457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.509512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-08T00:36:44.845678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.923313ms","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-04-08T00:36:44.845729Z","caller":"traceutil/trace.go:171","msg":"trace[1660049118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2098; }","duration":"183.440254ms","start":"2026-04-08T00:36:44.662238Z","end":"2026-04-08T00:36:44.845678Z","steps":["trace[1660049118] 'agreement among raft nodes before linearized reading' (duration: 183.162338ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:44.845783Z","caller":"traceutil/trace.go:171","msg":"trace[258316822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2098; }","duration":"119.091917ms","start":"2026-04-08T00:36:44.726668Z","end":"2026-04-08T00:36:44.84576Z","steps":["trace[258316822] 'agreement among raft nodes before linearized reading' (duration: 118.794331ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:44.845817Z","caller":"traceutil/trace.go:171","msg":"trace[1015571820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2098; }","duration":"121.588603ms","start":"2026-04-08T00:36:44.72416Z","end":"2026-04-08T00:36:44.845748Z","steps":["trace[1015571820] 'agreement among raft nodes before linearized reading' (duration: 121.38991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:45.058746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.967899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:36:45.059164Z","caller":"traceutil/trace.go:171","msg":"trace[330361019] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"208.237996ms","start":"2026-04-08T00:36:44.850881Z","end":"2026-04-08T00:36:45.059119Z","steps":["trace[330361019] 'process raft request' (duration: 33.80417ms)","trace[330361019] 'compare' (duration: 173.823055ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:45.05925Z","caller":"traceutil/trace.go:171","msg":"trace[1649939109] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"207.969701ms","start":"2026-04-08T00:36:44.85126Z","end":"2026-04-08T00:36:45.05923Z","steps":["trace[1649939109] 'process raft request' (duration: 207.661296ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:45.059461Z","caller":"traceutil/trace.go:171","msg":"trace[1320995238] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"208.077943ms","start":"2026-04-08T00:36:44.851355Z","end":"2026-04-08T00:36:45.059433Z","steps":["trace[1320995238] 'process raft request' (duration: 207.833558ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:45.059624Z","caller":"traceutil/trace.go:171","msg":"trace[98086085] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"208.046372ms","start":"2026-04-08T00:36:44.85156Z","end":"2026-04-08T00:36:45.059606Z","steps":["trace[98086085] 'process raft request' (duration: 207.93003ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:45.059612Z","caller":"traceutil/trace.go:171","msg":"trace[2055484495] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"208.111863ms","start":"2026-04-08T00:36:44.851464Z","end":"2026-04-08T00:36:45.059576Z","steps":["trace[2055484495] 'process raft request' (duration: 207.896519ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:07.768375Z","caller":"traceutil/trace.go:171","msg":"trace[255725548] linearizableReadLoop","detail":"{readStateIndex:2692; appliedIndex:2691; }","duration":"247.558964ms","start":"2026-04-08T00:38:07.520785Z","end":"2026-04-08T00:38:07.768344Z","steps":["trace[255725548] 'read index received' (duration: 201.123545ms)","trace[255725548] 'applied index is now lower than readState.Index' (duration: 46.433538ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:38:07.768448Z","caller":"traceutil/trace.go:171","msg":"trace[480042517] transaction","detail":"{read_only:false; response_revision:2597; number_of_response:1; }","duration":"274.478287ms","start":"2026-04-08T00:38:07.493923Z","end":"2026-04-08T00:38:07.768402Z","steps":["trace[480042517] 'process raft request' (duration: 227.961707ms)","trace[480042517] 'compare' (duration: 46.311195ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:38:07.768616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.082758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:38:07.768668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.266371ms","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-04-08T00:38:07.768741Z","caller":"traceutil/trace.go:171","msg":"trace[1109179753] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2597; }","duration":"267.238011ms","start":"2026-04-08T00:38:07.501477Z","end":"2026-04-08T00:38:07.768715Z","steps":["trace[1109179753] 'agreement among raft nodes before linearized reading' (duration: 267.007737ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:07.76878Z","caller":"traceutil/trace.go:171","msg":"trace[1247048854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2597; }","duration":"268.458774ms","start":"2026-04-08T00:38:07.500305Z","end":"2026-04-08T00:38:07.768764Z","steps":["trace[1247048854] 'agreement among raft nodes before linearized reading' (duration: 268.162709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:07.769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.185702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-08T00:38:07.769081Z","caller":"traceutil/trace.go:171","msg":"trace[1184892797] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2597; }","duration":"266.283773ms","start":"2026-04-08T00:38:07.502773Z","end":"2026-04-08T00:38:07.769057Z","steps":["trace[1184892797] 'agreement among raft nodes before linearized reading' (duration: 265.639052ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:07.77072Z","caller":"traceutil/trace.go:171","msg":"trace[1296809818] transaction","detail":"{read_only:false; response_revision:2598; number_of_response:1; }","duration":"245.362552ms","start":"2026-04-08T00:38:07.525333Z","end":"2026-04-08T00:38:07.770696Z","steps":["trace[1296809818] 'process raft request' (duration: 245.200769ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:07.770887Z","caller":"traceutil/trace.go:171","msg":"trace[1717800911] transaction","detail":"{read_only:false; response_revision:2599; number_of_response:1; }","duration":"169.1938ms","start":"2026-04-08T00:38:07.601646Z","end":"2026-04-08T00:38:07.77084Z","steps":["trace[1717800911] 'process raft request' (duration: 169.010927ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:40:54.210676Z","caller":"traceutil/trace.go:171","msg":"trace[502266067] transaction","detail":"{read_only:false; response_revision:3660; number_of_response:1; }","duration":"181.637985ms","start":"2026-04-08T00:40:54.02901Z","end":"2026-04-08T00:40:54.210648Z","steps":["trace[502266067] 'process raft request' (duration: 181.293437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:40:54.426044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.247714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:40:54.426144Z","caller":"traceutil/trace.go:171","msg":"trace[697450808] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:3660; }","duration":"107.417488ms","start":"2026-04-08T00:40:54.318704Z","end":"2026-04-08T00:40:54.426122Z","steps":["trace[697450808] 'count revisions from in-memory index tree' (duration: 107.168823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:40:54.426207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.27314ms","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-04-08T00:40:54.426303Z","caller":"traceutil/trace.go:171","msg":"trace[874041401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3660; }","duration":"118.416283ms","start":"2026-04-08T00:40:54.307865Z","end":"2026-04-08T00:40:54.426281Z","steps":["trace[874041401] 'range keys from in-memory index tree' (duration: 118.116137ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:40:54.426566Z","caller":"traceutil/trace.go:171","msg":"trace[22792654] transaction","detail":"{read_only:false; response_revision:3661; number_of_response:1; }","duration":"100.252004ms","start":"2026-04-08T00:40:54.326295Z","end":"2026-04-08T00:40:54.426547Z","steps":["trace[22792654] 'process raft request' (duration: 96.85958ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:08.707603Z","caller":"traceutil/trace.go:171","msg":"trace[1082923851] linearizableReadLoop","detail":"{readStateIndex:4709; appliedIndex:4708; }","duration":"107.125212ms","start":"2026-04-08T00:42:08.600453Z","end":"2026-04-08T00:42:08.707578Z","steps":["trace[1082923851] 'read index received' (duration: 102.001506ms)","trace[1082923851] 'applied index is now lower than readState.Index' (duration: 5.122356ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:08.707723Z","caller":"traceutil/trace.go:171","msg":"trace[372621571] transaction","detail":"{read_only:false; response_revision:4537; number_of_response:1; }","duration":"127.479202ms","start":"2026-04-08T00:42:08.580197Z","end":"2026-04-08T00:42:08.707676Z","steps":["trace[372621571] 'process raft request' (duration: 122.248044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:08.707868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.307315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-tls\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:42:08.707949Z","caller":"traceutil/trace.go:171","msg":"trace[1415597818] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-tls; range_end:; response_count:0; response_revision:4537; }","duration":"107.51521ms","start":"2026-04-08T00:42:08.600417Z","end":"2026-04-08T00:42:08.707932Z","steps":["trace[1415597818] 'agreement among raft nodes before linearized reading' (duration: 107.276395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:08.710216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.583889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-08T00:42:08.710315Z","caller":"traceutil/trace.go:171","msg":"trace[553303148] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4541; }","duration":"101.726121ms","start":"2026-04-08T00:42:08.608573Z","end":"2026-04-08T00:42:08.710299Z","steps":["trace[553303148] 'agreement among raft nodes before linearized reading' (duration: 99.787554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:08.712198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.464483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-08T00:42:08.712302Z","caller":"traceutil/trace.go:171","msg":"trace[2051709052] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4541; }","duration":"103.614416ms","start":"2026-04-08T00:42:08.608664Z","end":"2026-04-08T00:42:08.712278Z","steps":["trace[2051709052] 'agreement among raft nodes before linearized reading' (duration: 99.725224ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:13.973671Z","caller":"traceutil/trace.go:171","msg":"trace[1533237154] transaction","detail":"{read_only:false; response_revision:4707; number_of_response:1; }","duration":"118.940343ms","start":"2026-04-08T00:42:13.854706Z","end":"2026-04-08T00:42:13.973647Z","steps":["trace[1533237154] 'process raft request' (duration: 118.753769ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:14.551332Z","caller":"traceutil/trace.go:171","msg":"trace[1849867100] linearizableReadLoop","detail":"{readStateIndex:4896; appliedIndex:4895; }","duration":"153.243943ms","start":"2026-04-08T00:42:14.398069Z","end":"2026-04-08T00:42:14.551313Z","steps":["trace[1849867100] 'read index received' (duration: 152.695033ms)","trace[1849867100] 'applied index is now lower than readState.Index' (duration: 548.03µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:14.551497Z","caller":"traceutil/trace.go:171","msg":"trace[1847685149] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"161.816973ms","start":"2026-04-08T00:42:14.389666Z","end":"2026-04-08T00:42:14.551483Z","steps":["trace[1847685149] 'process raft request' (duration: 161.0843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:14.551515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.435296ms","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-04-08T00:42:14.551546Z","caller":"traceutil/trace.go:171","msg":"trace[326711218] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4717; }","duration":"153.508199ms","start":"2026-04-08T00:42:14.39803Z","end":"2026-04-08T00:42:14.551538Z","steps":["trace[326711218] 'agreement among raft nodes before linearized reading' (duration: 153.359775ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:14.737118Z","caller":"traceutil/trace.go:171","msg":"trace[541520254] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"122.764984ms","start":"2026-04-08T00:42:14.61431Z","end":"2026-04-08T00:42:14.737075Z","steps":["trace[541520254] 'process raft request' (duration: 83.390979ms)","trace[541520254] 'compare' (duration: 39.171262ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:14.744624Z","caller":"traceutil/trace.go:171","msg":"trace[229283726] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"123.349985ms","start":"2026-04-08T00:42:14.621249Z","end":"2026-04-08T00:42:14.744599Z","steps":["trace[229283726] 'process raft request' (duration: 123.171572ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:15.756395Z","caller":"traceutil/trace.go:171","msg":"trace[971779559] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"126.580275ms","start":"2026-04-08T00:42:15.629778Z","end":"2026-04-08T00:42:15.756359Z","steps":["trace[971779559] 'process raft request' (duration: 57.494834ms)","trace[971779559] 'compare' (duration: 68.865097ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:15.756523Z","caller":"traceutil/trace.go:171","msg":"trace[1670045787] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"121.379388ms","start":"2026-04-08T00:42:15.635105Z","end":"2026-04-08T00:42:15.756484Z","steps":["trace[1670045787] 'process raft request' (duration: 121.214685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:18.775671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.554757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:42:18.775815Z","caller":"traceutil/trace.go:171","msg":"trace[312375068] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"703.583158ms","start":"2026-04-08T00:42:18.07221Z","end":"2026-04-08T00:42:18.775793Z","steps":["trace[312375068] 'process raft request' (duration: 471.801857ms)","trace[312375068] 'compare' (duration: 231.418024ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:42:18.775882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:42:18.07219Z","time spent":"703.66208ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:42:18.872868Z","caller":"traceutil/trace.go:171","msg":"trace[1903101608] linearizableReadLoop","detail":"{readStateIndex:5050; appliedIndex:5048; }","duration":"677.236305ms","start":"2026-04-08T00:42:18.195605Z","end":"2026-04-08T00:42:18.872841Z","steps":["trace[1903101608] 'read index received' (duration: 348.417781ms)","trace[1903101608] 'applied index is now lower than readState.Index' (duration: 328.817074ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:18.873132Z","caller":"traceutil/trace.go:171","msg":"trace[2016975180] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"773.446642ms","start":"2026-04-08T00:42:18.099672Z","end":"2026-04-08T00:42:18.873119Z","steps":["trace[2016975180] 'process raft request' (duration: 773.046845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:18.873239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:42:18.099645Z","time spent":"773.515174ms","remote":"127.0.0.1:57044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1811,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T00:42:18.873539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.938999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4292591a-2873-4c97-8f29-3ef6c05f9702\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-08T00:42:18.873586Z","caller":"traceutil/trace.go:171","msg":"trace[1677309533] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4292591a-2873-4c97-8f29-3ef6c05f9702; range_end:; response_count:1; response_revision:4868; }","duration":"678.045261ms","start":"2026-04-08T00:42:18.195529Z","end":"2026-04-08T00:42:18.873575Z","steps":["trace[1677309533] 'agreement among raft nodes before linearized reading' (duration: 677.872448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:18.873617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:42:18.195499Z","time spent":"678.111231ms","remote":"127.0.0.1:48800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3623,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4292591a-2873-4c97-8f29-3ef6c05f9702\" "} {"level":"warn","ts":"2026-04-08T00:42:18.946195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.847917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:42:18.94622Z","caller":"traceutil/trace.go:171","msg":"trace[296214084] transaction","detail":"{read_only:false; response_revision:4869; number_of_response:1; }","duration":"118.439342ms","start":"2026-04-08T00:42:18.827716Z","end":"2026-04-08T00:42:18.946155Z","steps":["trace[296214084] 'process raft request' (duration: 94.268651ms)","trace[296214084] 'compare' (duration: 23.837175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:42:18.946294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.693429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4292591a-2873-4c97-8f29-3ef6c05f9702\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-08T00:42:18.946314Z","caller":"traceutil/trace.go:171","msg":"trace[1476277068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4869; }","duration":"168.042861ms","start":"2026-04-08T00:42:18.778248Z","end":"2026-04-08T00:42:18.94629Z","steps":["trace[1476277068] 'agreement among raft nodes before linearized reading' (duration: 167.777836ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:18.946368Z","caller":"traceutil/trace.go:171","msg":"trace[1996213558] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4292591a-2873-4c97-8f29-3ef6c05f9702; range_end:; response_count:1; response_revision:4869; }","duration":"277.784081ms","start":"2026-04-08T00:42:18.668568Z","end":"2026-04-08T00:42:18.946352Z","steps":["trace[1996213558] 'agreement among raft nodes before linearized reading' (duration: 277.491636ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:19.273064Z","caller":"traceutil/trace.go:171","msg":"trace[2118566403] linearizableReadLoop","detail":"{readStateIndex:5057; appliedIndex:5056; }","duration":"107.225034ms","start":"2026-04-08T00:42:19.165815Z","end":"2026-04-08T00:42:19.27304Z","steps":["trace[2118566403] 'read index received' (duration: 17.890255ms)","trace[2118566403] 'applied index is now lower than readState.Index' (duration: 89.333509ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:19.273159Z","caller":"traceutil/trace.go:171","msg":"trace[927684563] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"115.928886ms","start":"2026-04-08T00:42:19.157175Z","end":"2026-04-08T00:42:19.273104Z","steps":["trace[927684563] 'process raft request' (duration: 26.492105ms)","trace[927684563] 'compare' (duration: 89.203057ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:42:19.273323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.691538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-04-08T00:42:19.273335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.662888ms","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-04-08T00:42:19.273417Z","caller":"traceutil/trace.go:171","msg":"trace[1807111093] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4874; }","duration":"119.8303ms","start":"2026-04-08T00:42:19.153564Z","end":"2026-04-08T00:42:19.273394Z","steps":["trace[1807111093] 'agreement among raft nodes before linearized reading' (duration: 119.582665ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:19.273431Z","caller":"traceutil/trace.go:171","msg":"trace[1423627853] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4874; }","duration":"118.79319ms","start":"2026-04-08T00:42:19.154612Z","end":"2026-04-08T00:42:19.273406Z","steps":["trace[1423627853] 'agreement among raft nodes before linearized reading' (duration: 118.472314ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:19.31244Z","caller":"traceutil/trace.go:171","msg":"trace[1265265199] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"141.993414ms","start":"2026-04-08T00:42:19.170406Z","end":"2026-04-08T00:42:19.312399Z","steps":["trace[1265265199] 'process raft request' (duration: 141.80866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:19.312945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.427714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:812096"} {"level":"info","ts":"2026-04-08T00:42:19.313029Z","caller":"traceutil/trace.go:171","msg":"trace[723517194] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4875; }","duration":"102.524065ms","start":"2026-04-08T00:42:19.210485Z","end":"2026-04-08T00:42:19.313009Z","steps":["trace[723517194] 'agreement among raft nodes before linearized reading' (duration: 101.861794ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:19.452183Z","caller":"traceutil/trace.go:171","msg":"trace[2123781432] linearizableReadLoop","detail":"{readStateIndex:5060; appliedIndex:5058; }","duration":"135.016343ms","start":"2026-04-08T00:42:19.317143Z","end":"2026-04-08T00:42:19.45216Z","steps":["trace[2123781432] 'read index received' (duration: 63.314413ms)","trace[2123781432] 'applied index is now lower than readState.Index' (duration: 71.70077ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:19.452385Z","caller":"traceutil/trace.go:171","msg":"trace[1436283741] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"173.602654ms","start":"2026-04-08T00:42:19.278706Z","end":"2026-04-08T00:42:19.452308Z","steps":["trace[1436283741] 'process raft request' (duration: 173.379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:19.45259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.428522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-08T00:42:19.452388Z","caller":"traceutil/trace.go:171","msg":"trace[2132438932] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"174.481641ms","start":"2026-04-08T00:42:19.277799Z","end":"2026-04-08T00:42:19.45228Z","steps":["trace[2132438932] 'process raft request' (duration: 102.597417ms)","trace[2132438932] 'compare' (duration: 70.853764ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:19.452685Z","caller":"traceutil/trace.go:171","msg":"trace[155555122] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4877; }","duration":"135.551023ms","start":"2026-04-08T00:42:19.317112Z","end":"2026-04-08T00:42:19.452663Z","steps":["trace[155555122] 'agreement among raft nodes before linearized reading' (duration: 135.224768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:19.452865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.092852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:812096"} {"level":"info","ts":"2026-04-08T00:42:19.452918Z","caller":"traceutil/trace.go:171","msg":"trace[106737996] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4877; }","duration":"129.170464ms","start":"2026-04-08T00:42:19.323733Z","end":"2026-04-08T00:42:19.452903Z","steps":["trace[106737996] 'agreement among raft nodes before linearized reading' (duration: 128.627624ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:23.452312Z","caller":"traceutil/trace.go:171","msg":"trace[321033394] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"104.517884ms","start":"2026-04-08T00:42:23.347773Z","end":"2026-04-08T00:42:23.452291Z","steps":["trace[321033394] 'process raft request' (duration: 100.261284ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:23.452481Z","caller":"traceutil/trace.go:171","msg":"trace[1465855340] linearizableReadLoop","detail":"{readStateIndex:5147; appliedIndex:5145; }","duration":"102.487045ms","start":"2026-04-08T00:42:23.349963Z","end":"2026-04-08T00:42:23.45245Z","steps":["trace[1465855340] 'read index received' (duration: 98.085583ms)","trace[1465855340] 'applied index is now lower than readState.Index' (duration: 4.399232ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:23.452577Z","caller":"traceutil/trace.go:171","msg":"trace[1637026578] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"102.856473ms","start":"2026-04-08T00:42:23.34968Z","end":"2026-04-08T00:42:23.452536Z","steps":["trace[1637026578] 'process raft request' (duration: 102.568448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:23.453116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.134367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:26 size:822328"} {"level":"info","ts":"2026-04-08T00:42:23.453158Z","caller":"traceutil/trace.go:171","msg":"trace[135275829] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:26; response_revision:4962; }","duration":"103.213998ms","start":"2026-04-08T00:42:23.349934Z","end":"2026-04-08T00:42:23.453148Z","steps":["trace[135275829] 'agreement among raft nodes before linearized reading' (duration: 102.665128ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:29.12035Z","caller":"traceutil/trace.go:171","msg":"trace[465819868] linearizableReadLoop","detail":"{readStateIndex:5244; appliedIndex:5243; }","duration":"114.849437ms","start":"2026-04-08T00:42:29.00547Z","end":"2026-04-08T00:42:29.120319Z","steps":["trace[465819868] 'read index received' (duration: 103.075626ms)","trace[465819868] 'applied index is now lower than readState.Index' (duration: 11.77201ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:29.12056Z","caller":"traceutil/trace.go:171","msg":"trace[912604953] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"136.562082ms","start":"2026-04-08T00:42:28.983954Z","end":"2026-04-08T00:42:29.120516Z","steps":["trace[912604953] 'process raft request' (duration: 124.637469ms)","trace[912604953] 'compare' (duration: 11.574047ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:42:29.120628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.141381ms","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-04-08T00:42:29.120668Z","caller":"traceutil/trace.go:171","msg":"trace[733237656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5056; }","duration":"115.233914ms","start":"2026-04-08T00:42:29.005423Z","end":"2026-04-08T00:42:29.120657Z","steps":["trace[733237656] 'agreement among raft nodes before linearized reading' (duration: 115.01629ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:29.231712Z","caller":"traceutil/trace.go:171","msg":"trace[111800066] transaction","detail":"{read_only:false; response_revision:5057; number_of_response:1; }","duration":"107.094711ms","start":"2026-04-08T00:42:29.124584Z","end":"2026-04-08T00:42:29.231679Z","steps":["trace[111800066] 'process raft request' (duration: 106.803286ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:30.362834Z","caller":"traceutil/trace.go:171","msg":"trace[854098048] transaction","detail":"{read_only:false; response_revision:5079; number_of_response:1; }","duration":"186.743349ms","start":"2026-04-08T00:42:30.176061Z","end":"2026-04-08T00:42:30.362805Z","steps":["trace[854098048] 'process raft request' (duration: 186.577456ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:35.71792Z","caller":"traceutil/trace.go:171","msg":"trace[643630677] linearizableReadLoop","detail":"{readStateIndex:5325; appliedIndex:5324; }","duration":"165.164247ms","start":"2026-04-08T00:42:35.552716Z","end":"2026-04-08T00:42:35.71788Z","steps":["trace[643630677] 'read index received' (duration: 143.798098ms)","trace[643630677] 'applied index is now lower than readState.Index' (duration: 21.364859ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:35.717969Z","caller":"traceutil/trace.go:171","msg":"trace[994611939] transaction","detail":"{read_only:false; response_revision:5135; number_of_response:1; }","duration":"165.821798ms","start":"2026-04-08T00:42:35.552099Z","end":"2026-04-08T00:42:35.717921Z","steps":["trace[994611939] 'process raft request' (duration: 144.48664ms)","trace[994611939] 'compare' (duration: 21.067233ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:42:35.718235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.506932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:54148"} {"level":"info","ts":"2026-04-08T00:42:35.718275Z","caller":"traceutil/trace.go:171","msg":"trace[586632025] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5135; }","duration":"165.613534ms","start":"2026-04-08T00:42:35.552651Z","end":"2026-04-08T00:42:35.718264Z","steps":["trace[586632025] 'agreement among raft nodes before linearized reading' (duration: 165.35446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:38.001882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.071999ms","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-04-08T00:42:38.001974Z","caller":"traceutil/trace.go:171","msg":"trace[1938074098] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5217; }","duration":"154.185462ms","start":"2026-04-08T00:42:37.847767Z","end":"2026-04-08T00:42:38.001952Z","steps":["trace[1938074098] 'range keys from in-memory index tree' (duration: 153.862135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:38.002334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.821082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f817c42e-5a4c-4bb3-9e6a-7bb32181e078\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-08T00:42:38.00245Z","caller":"traceutil/trace.go:171","msg":"trace[2116945144] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f817c42e-5a4c-4bb3-9e6a-7bb32181e078; range_end:; response_count:1; response_revision:5217; }","duration":"140.007306ms","start":"2026-04-08T00:42:37.862415Z","end":"2026-04-08T00:42:38.002422Z","steps":["trace[2116945144] 'range keys from in-memory index tree' (duration: 139.443305ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:38.629944Z","caller":"traceutil/trace.go:171","msg":"trace[299855674] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"622.586435ms","start":"2026-04-08T00:42:38.007332Z","end":"2026-04-08T00:42:38.629918Z","steps":["trace[299855674] 'process raft request' (duration: 622.447222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:38.630129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:42:38.007309Z","time spent":"622.711247ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:42:38.87359Z","caller":"traceutil/trace.go:171","msg":"trace[1569315572] linearizableReadLoop","detail":"{readStateIndex:5409; appliedIndex:5407; }","duration":"304.227696ms","start":"2026-04-08T00:42:38.569331Z","end":"2026-04-08T00:42:38.873559Z","steps":["trace[1569315572] 'read index received' (duration: 60.459731ms)","trace[1569315572] 'applied index is now lower than readState.Index' (duration: 243.766435ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:38.873732Z","caller":"traceutil/trace.go:171","msg":"trace[2039764353] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"862.512377ms","start":"2026-04-08T00:42:38.011193Z","end":"2026-04-08T00:42:38.873705Z","steps":["trace[2039764353] 'process raft request' (duration: 862.176431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:38.873878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:42:38.011163Z","time spent":"862.63726ms","remote":"127.0.0.1:48800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3748,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:42:38.873853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.49387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f817c42e-5a4c-4bb3-9e6a-7bb32181e078\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-04-08T00:42:38.873956Z","caller":"traceutil/trace.go:171","msg":"trace[141885195] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f817c42e-5a4c-4bb3-9e6a-7bb32181e078; range_end:; response_count:1; response_revision:5219; }","duration":"304.613363ms","start":"2026-04-08T00:42:38.569319Z","end":"2026-04-08T00:42:38.873932Z","steps":["trace[141885195] 'agreement among raft nodes before linearized reading' (duration: 304.405909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:38.874018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:42:38.569266Z","time spent":"304.742045ms","remote":"127.0.0.1:48800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3785,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f817c42e-5a4c-4bb3-9e6a-7bb32181e078\" "} {"level":"warn","ts":"2026-04-08T00:42:38.880564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.851173ms","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-04-08T00:42:38.880629Z","caller":"traceutil/trace.go:171","msg":"trace[767399866] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5220; }","duration":"170.964355ms","start":"2026-04-08T00:42:38.709648Z","end":"2026-04-08T00:42:38.880612Z","steps":["trace[767399866] 'agreement among raft nodes before linearized reading' (duration: 170.836202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:38.880682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.544054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:42:38.880745Z","caller":"traceutil/trace.go:171","msg":"trace[1303604170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5220; }","duration":"104.672527ms","start":"2026-04-08T00:42:38.776058Z","end":"2026-04-08T00:42:38.88073Z","steps":["trace[1303604170] 'agreement among raft nodes before linearized reading' (duration: 104.557925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:38.880817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.168909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:42:38.880938Z","caller":"traceutil/trace.go:171","msg":"trace[664409690] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:5220; }","duration":"140.322002ms","start":"2026-04-08T00:42:38.740548Z","end":"2026-04-08T00:42:38.88087Z","steps":["trace[664409690] 'agreement among raft nodes before linearized reading' (duration: 140.132369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:41.114176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.527573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-04-08T00:42:41.114301Z","caller":"traceutil/trace.go:171","msg":"trace[711415264] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5273; }","duration":"112.720575ms","start":"2026-04-08T00:42:41.00155Z","end":"2026-04-08T00:42:41.114271Z","steps":["trace[711415264] 'range keys from in-memory index tree' (duration: 112.265938ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:47.953659Z","caller":"traceutil/trace.go:171","msg":"trace[2110493254] linearizableReadLoop","detail":"{readStateIndex:5584; appliedIndex:5583; }","duration":"177.465187ms","start":"2026-04-08T00:42:47.776162Z","end":"2026-04-08T00:42:47.953627Z","steps":["trace[2110493254] 'read index received' (duration: 120.40431ms)","trace[2110493254] 'applied index is now lower than readState.Index' (duration: 57.059507ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:47.953666Z","caller":"traceutil/trace.go:171","msg":"trace[1017375536] transaction","detail":"{read_only:false; response_revision:5388; number_of_response:1; }","duration":"177.579749ms","start":"2026-04-08T00:42:47.77605Z","end":"2026-04-08T00:42:47.95363Z","steps":["trace[1017375536] 'process raft request' (duration: 120.530233ms)","trace[1017375536] 'compare' (duration: 56.850682ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:42:47.95384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-04-08T00:42:47.953854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.806158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:42:47.953894Z","caller":"traceutil/trace.go:171","msg":"trace[944548691] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5388; }","duration":"177.764102ms","start":"2026-04-08T00:42:47.776116Z","end":"2026-04-08T00:42:47.95388Z","steps":["trace[944548691] 'agreement among raft nodes before linearized reading' (duration: 177.590498ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:47.953895Z","caller":"traceutil/trace.go:171","msg":"trace[805740564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5388; }","duration":"173.88192ms","start":"2026-04-08T00:42:47.780002Z","end":"2026-04-08T00:42:47.953884Z","steps":["trace[805740564] 'agreement among raft nodes before linearized reading' (duration: 173.775958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:47.953947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.594654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-znhmh\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T00:42:47.954026Z","caller":"traceutil/trace.go:171","msg":"trace[1382890498] range","detail":"{range_begin:/registry/pods/openstack/keepalived-znhmh; range_end:; response_count:1; response_revision:5388; }","duration":"173.699386ms","start":"2026-04-08T00:42:47.780309Z","end":"2026-04-08T00:42:47.954008Z","steps":["trace[1382890498] 'agreement among raft nodes before linearized reading' (duration: 173.519883ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:48.101087Z","caller":"traceutil/trace.go:171","msg":"trace[2021734505] linearizableReadLoop","detail":"{readStateIndex:5585; appliedIndex:5584; }","duration":"142.527794ms","start":"2026-04-08T00:42:47.95853Z","end":"2026-04-08T00:42:48.101058Z","steps":["trace[2021734505] 'read index received' (duration: 140.993165ms)","trace[2021734505] 'applied index is now lower than readState.Index' (duration: 1.533419ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:48.101165Z","caller":"traceutil/trace.go:171","msg":"trace[1639111638] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"143.296137ms","start":"2026-04-08T00:42:47.957841Z","end":"2026-04-08T00:42:48.101137Z","steps":["trace[1639111638] 'process raft request' (duration: 141.752189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:48.102374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.818288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:64717"} {"level":"info","ts":"2026-04-08T00:42:48.102445Z","caller":"traceutil/trace.go:171","msg":"trace[2019194218] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5389; }","duration":"143.917789ms","start":"2026-04-08T00:42:47.958504Z","end":"2026-04-08T00:42:48.102422Z","steps":["trace[2019194218] 'agreement among raft nodes before linearized reading' (duration: 142.698156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:52.179551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.238044ms","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-04-08T00:42:52.179652Z","caller":"traceutil/trace.go:171","msg":"trace[1641783789] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5420; }","duration":"503.389476ms","start":"2026-04-08T00:42:51.676243Z","end":"2026-04-08T00:42:52.179633Z","steps":["trace[1641783789] 'range keys from in-memory index tree' (duration: 503.070461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:52.179699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:42:51.676224Z","time spent":"503.465638ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T00:42:52.179799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.899773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:42:52.179894Z","caller":"traceutil/trace.go:171","msg":"trace[2062594045] linearizableReadLoop","detail":"{readStateIndex:5618; appliedIndex:5617; }","duration":"401.235008ms","start":"2026-04-08T00:42:51.778644Z","end":"2026-04-08T00:42:52.179879Z","steps":["trace[2062594045] 'read index received' (duration: 291.179811ms)","trace[2062594045] 'applied index is now lower than readState.Index' (duration: 110.054077ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:52.17998Z","caller":"traceutil/trace.go:171","msg":"trace[530921688] transaction","detail":"{read_only:false; response_revision:5421; number_of_response:1; }","duration":"423.050966ms","start":"2026-04-08T00:42:51.756915Z","end":"2026-04-08T00:42:52.179966Z","steps":["trace[530921688] 'process raft request' (duration: 312.895848ms)","trace[530921688] 'compare' (duration: 109.618219ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:42:52.180039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:42:51.756891Z","time spent":"423.119367ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:42:52.180223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.188771ms","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:5"} {"level":"warn","ts":"2026-04-08T00:42:52.18024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.586525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:42:52.180299Z","caller":"traceutil/trace.go:171","msg":"trace[345826805] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5421; }","duration":"276.483138ms","start":"2026-04-08T00:42:51.903794Z","end":"2026-04-08T00:42:52.180277Z","steps":["trace[345826805] 'agreement among raft nodes before linearized reading' (duration: 276.209602ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:52.180311Z","caller":"traceutil/trace.go:171","msg":"trace[892656545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5421; }","duration":"401.691077ms","start":"2026-04-08T00:42:51.7786Z","end":"2026-04-08T00:42:52.180291Z","steps":["trace[892656545] 'agreement among raft nodes before linearized reading' (duration: 401.571015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:52.180312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.313779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-04-08T00:42:52.18036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:42:51.778583Z","time spent":"401.769468ms","remote":"127.0.0.1:48890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T00:42:52.180386Z","caller":"traceutil/trace.go:171","msg":"trace[1174861533] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5421; }","duration":"143.427071ms","start":"2026-04-08T00:42:52.036935Z","end":"2026-04-08T00:42:52.180363Z","steps":["trace[1174861533] 'agreement among raft nodes before linearized reading' (duration: 143.213517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:52.180244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.654394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-04-08T00:42:52.180671Z","caller":"traceutil/trace.go:171","msg":"trace[2139968121] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5421; }","duration":"145.076832ms","start":"2026-04-08T00:42:52.035541Z","end":"2026-04-08T00:42:52.180618Z","steps":["trace[2139968121] 'agreement among raft nodes before linearized reading' (duration: 144.610003ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:52.414005Z","caller":"traceutil/trace.go:171","msg":"trace[1180013507] transaction","detail":"{read_only:false; response_revision:5423; number_of_response:1; }","duration":"228.851817ms","start":"2026-04-08T00:42:52.185127Z","end":"2026-04-08T00:42:52.413979Z","steps":["trace[1180013507] 'process raft request' (duration: 228.578831ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:52.414197Z","caller":"traceutil/trace.go:171","msg":"trace[1971783560] transaction","detail":"{read_only:false; response_revision:5424; number_of_response:1; }","duration":"228.221265ms","start":"2026-04-08T00:42:52.185966Z","end":"2026-04-08T00:42:52.414187Z","steps":["trace[1971783560] 'process raft request' (duration: 227.909988ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:52.414581Z","caller":"traceutil/trace.go:171","msg":"trace[1465680297] linearizableReadLoop","detail":"{readStateIndex:5620; appliedIndex:5618; }","duration":"228.421058ms","start":"2026-04-08T00:42:52.185857Z","end":"2026-04-08T00:42:52.414278Z","steps":["trace[1465680297] 'read index received' (duration: 30.344816ms)","trace[1465680297] 'applied index is now lower than readState.Index' (duration: 198.073752ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:52.41465Z","caller":"traceutil/trace.go:171","msg":"trace[298575691] transaction","detail":"{read_only:false; response_revision:5425; number_of_response:1; }","duration":"223.736131ms","start":"2026-04-08T00:42:52.190588Z","end":"2026-04-08T00:42:52.414324Z","steps":["trace[298575691] 'process raft request' (duration: 223.337724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:52.414694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.808585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:65010"} {"level":"info","ts":"2026-04-08T00:42:52.414777Z","caller":"traceutil/trace.go:171","msg":"trace[1302061158] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5425; }","duration":"228.929378ms","start":"2026-04-08T00:42:52.185828Z","end":"2026-04-08T00:42:52.414757Z","steps":["trace[1302061158] 'agreement among raft nodes before linearized reading' (duration: 228.614812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:52.595832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.147076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:42:52.596066Z","caller":"traceutil/trace.go:171","msg":"trace[138104257] transaction","detail":"{read_only:false; response_revision:5427; number_of_response:1; }","duration":"175.251296ms","start":"2026-04-08T00:42:52.42078Z","end":"2026-04-08T00:42:52.596032Z","steps":["trace[138104257] 'process raft request' (duration: 70.792164ms)","trace[138104257] 'compare' (duration: 103.991463ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:52.596117Z","caller":"traceutil/trace.go:171","msg":"trace[805060904] linearizableReadLoop","detail":"{readStateIndex:5624; appliedIndex:5622; }","duration":"174.502811ms","start":"2026-04-08T00:42:52.421596Z","end":"2026-04-08T00:42:52.596099Z","steps":["trace[805060904] 'read index received' (duration: 28.110775ms)","trace[805060904] 'applied index is now lower than readState.Index' (duration: 146.390656ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:42:52.596199Z","caller":"traceutil/trace.go:171","msg":"trace[80517721] transaction","detail":"{read_only:false; response_revision:5428; number_of_response:1; }","duration":"174.567863ms","start":"2026-04-08T00:42:52.421606Z","end":"2026-04-08T00:42:52.596174Z","steps":["trace[80517721] 'process raft request' (duration: 174.341468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:52.596269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.666004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-08T00:42:52.596264Z","caller":"traceutil/trace.go:171","msg":"trace[875854156] transaction","detail":"{read_only:false; response_revision:5429; number_of_response:1; }","duration":"174.572452ms","start":"2026-04-08T00:42:52.421653Z","end":"2026-04-08T00:42:52.596225Z","steps":["trace[875854156] 'process raft request' (duration: 174.399309ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:52.596302Z","caller":"traceutil/trace.go:171","msg":"trace[1627894485] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:5429; }","duration":"174.710685ms","start":"2026-04-08T00:42:52.42158Z","end":"2026-04-08T00:42:52.596291Z","steps":["trace[1627894485] 'agreement among raft nodes before linearized reading' (duration: 174.627413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:52.596453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.581194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:128837"} {"level":"info","ts":"2026-04-08T00:42:52.596519Z","caller":"traceutil/trace.go:171","msg":"trace[1158319423] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5429; }","duration":"173.664485ms","start":"2026-04-08T00:42:52.422839Z","end":"2026-04-08T00:42:52.596504Z","steps":["trace[1158319423] 'agreement among raft nodes before linearized reading' (duration: 173.426041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:52.943822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.681781ms","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-04-08T00:42:52.943924Z","caller":"traceutil/trace.go:171","msg":"trace[1641766675] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5430; }","duration":"245.834324ms","start":"2026-04-08T00:42:52.698069Z","end":"2026-04-08T00:42:52.943904Z","steps":["trace[1641766675] 'range keys from in-memory index tree' (duration: 245.438436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:35.766424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.166608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:43:35.766515Z","caller":"traceutil/trace.go:171","msg":"trace[105397372] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5795; }","duration":"170.309271ms","start":"2026-04-08T00:43:35.596186Z","end":"2026-04-08T00:43:35.766496Z","steps":["trace[105397372] 'range keys from in-memory index tree' (duration: 170.084537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:35.766491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.199459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"warn","ts":"2026-04-08T00:43:35.766491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.031336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:43:35.766581Z","caller":"traceutil/trace.go:171","msg":"trace[294551728] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5795; }","duration":"155.347022ms","start":"2026-04-08T00:43:35.611213Z","end":"2026-04-08T00:43:35.76656Z","steps":["trace[294551728] 'range keys from in-memory index tree' (duration: 155.027106ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:35.766597Z","caller":"traceutil/trace.go:171","msg":"trace[849376928] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5795; }","duration":"170.22446ms","start":"2026-04-08T00:43:35.596353Z","end":"2026-04-08T00:43:35.766577Z","steps":["trace[849376928] 'range keys from in-memory index tree' (duration: 169.836543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:35.76671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.266076ms","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-04-08T00:43:35.766814Z","caller":"traceutil/trace.go:171","msg":"trace[1617519131] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5795; }","duration":"180.431969ms","start":"2026-04-08T00:43:35.586356Z","end":"2026-04-08T00:43:35.766788Z","steps":["trace[1617519131] 'range keys from in-memory index tree' (duration: 180.065733ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:35.975276Z","caller":"traceutil/trace.go:171","msg":"trace[524072079] transaction","detail":"{read_only:false; response_revision:5799; number_of_response:1; }","duration":"112.720449ms","start":"2026-04-08T00:43:35.862518Z","end":"2026-04-08T00:43:35.975239Z","steps":["trace[524072079] 'process raft request' (duration: 112.625007ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:35.975437Z","caller":"traceutil/trace.go:171","msg":"trace[929356485] transaction","detail":"{read_only:false; response_revision:5798; number_of_response:1; }","duration":"113.169926ms","start":"2026-04-08T00:43:35.862209Z","end":"2026-04-08T00:43:35.975379Z","steps":["trace[929356485] 'process raft request' (duration: 75.574796ms)","trace[929356485] 'compare' (duration: 37.181013ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:43:36.544377Z","caller":"traceutil/trace.go:171","msg":"trace[751203832] transaction","detail":"{read_only:false; response_revision:5800; number_of_response:1; }","duration":"110.816013ms","start":"2026-04-08T00:43:36.433527Z","end":"2026-04-08T00:43:36.544343Z","steps":["trace[751203832] 'process raft request' (duration: 110.590539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:43:48.093407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.410931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:43:48.093599Z","caller":"traceutil/trace.go:171","msg":"trace[1090081675] transaction","detail":"{read_only:false; response_revision:5885; number_of_response:1; }","duration":"157.278837ms","start":"2026-04-08T00:43:47.936278Z","end":"2026-04-08T00:43:48.093556Z","steps":["trace[1090081675] 'process raft request' (duration: 35.616693ms)","trace[1090081675] 'compare' (duration: 121.126745ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:43:48.731051Z","caller":"traceutil/trace.go:171","msg":"trace[1364507993] transaction","detail":"{read_only:false; response_revision:5889; number_of_response:1; }","duration":"123.290395ms","start":"2026-04-08T00:43:48.607731Z","end":"2026-04-08T00:43:48.731021Z","steps":["trace[1364507993] 'process raft request' (duration: 122.813706ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:43:51.088643Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2826} {"level":"info","ts":"2026-04-08T00:43:51.171061Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2826,"took":"79.908698ms","hash":804005100,"current-db-size-bytes":58003456,"current-db-size":"58 MB","current-db-size-in-use-bytes":32636928,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-08T00:43:51.171116Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":804005100,"revision":2826,"compact-revision":-1} {"level":"info","ts":"2026-04-08T00:44:13.443511Z","caller":"traceutil/trace.go:171","msg":"trace[803708793] transaction","detail":"{read_only:false; response_revision:6072; number_of_response:1; }","duration":"120.911185ms","start":"2026-04-08T00:44:13.322574Z","end":"2026-04-08T00:44:13.443485Z","steps":["trace[803708793] 'process raft request' (duration: 120.745763ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:17.085595Z","caller":"traceutil/trace.go:171","msg":"trace[461504166] linearizableReadLoop","detail":"{readStateIndex:6311; appliedIndex:6310; }","duration":"146.169508ms","start":"2026-04-08T00:44:16.939398Z","end":"2026-04-08T00:44:17.085568Z","steps":["trace[461504166] 'read index received' (duration: 49.569515ms)","trace[461504166] 'applied index is now lower than readState.Index' (duration: 96.594993ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:44:17.085855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.026066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-04-08T00:44:17.08573Z","caller":"traceutil/trace.go:171","msg":"trace[565500272] transaction","detail":"{read_only:false; response_revision:6094; number_of_response:1; }","duration":"148.172675ms","start":"2026-04-08T00:44:16.937519Z","end":"2026-04-08T00:44:17.085691Z","steps":["trace[565500272] 'process raft request' (duration: 106.433636ms)","trace[565500272] 'compare' (duration: 41.497534ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:44:17.085903Z","caller":"traceutil/trace.go:171","msg":"trace[70569875] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6094; }","duration":"116.132548ms","start":"2026-04-08T00:44:16.969755Z","end":"2026-04-08T00:44:17.085888Z","steps":["trace[70569875] 'agreement among raft nodes before linearized reading' (duration: 115.980175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:17.086282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.82803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:13 size:110323"} {"level":"info","ts":"2026-04-08T00:44:17.086419Z","caller":"traceutil/trace.go:171","msg":"trace[1105604513] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:13; response_revision:6094; }","duration":"147.010434ms","start":"2026-04-08T00:44:16.939376Z","end":"2026-04-08T00:44:17.086387Z","steps":["trace[1105604513] 'agreement among raft nodes before linearized reading' (duration: 146.371532ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:19.023048Z","caller":"traceutil/trace.go:171","msg":"trace[1528341347] transaction","detail":"{read_only:false; response_revision:6105; number_of_response:1; }","duration":"107.514475ms","start":"2026-04-08T00:44:18.9155Z","end":"2026-04-08T00:44:19.023014Z","steps":["trace[1528341347] 'process raft request' (duration: 78.679318ms)","trace[1528341347] 'compare' (duration: 28.625204ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:44:19.204834Z","caller":"traceutil/trace.go:171","msg":"trace[1871160808] transaction","detail":"{read_only:false; response_revision:6107; number_of_response:1; }","duration":"107.613808ms","start":"2026-04-08T00:44:19.097197Z","end":"2026-04-08T00:44:19.204811Z","steps":["trace[1871160808] 'process raft request' (duration: 107.434975ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:19.221048Z","caller":"traceutil/trace.go:171","msg":"trace[1394836202] linearizableReadLoop","detail":"{readStateIndex:6325; appliedIndex:6323; }","duration":"121.837594ms","start":"2026-04-08T00:44:19.099183Z","end":"2026-04-08T00:44:19.22102Z","steps":["trace[1394836202] 'read index received' (duration: 105.60368ms)","trace[1394836202] 'applied index is now lower than readState.Index' (duration: 16.232864ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:44:19.221148Z","caller":"traceutil/trace.go:171","msg":"trace[212970716] transaction","detail":"{read_only:false; response_revision:6108; number_of_response:1; }","duration":"123.488283ms","start":"2026-04-08T00:44:19.097633Z","end":"2026-04-08T00:44:19.221121Z","steps":["trace[212970716] 'process raft request' (duration: 123.227389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:19.221456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.252191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:13 size:110223"} {"level":"info","ts":"2026-04-08T00:44:19.221504Z","caller":"traceutil/trace.go:171","msg":"trace[1316730931] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:13; response_revision:6108; }","duration":"122.347572ms","start":"2026-04-08T00:44:19.099145Z","end":"2026-04-08T00:44:19.221492Z","steps":["trace[1316730931] 'agreement among raft nodes before linearized reading' (duration: 121.998976ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:19.349705Z","caller":"traceutil/trace.go:171","msg":"trace[485057625] linearizableReadLoop","detail":"{readStateIndex:6326; appliedIndex:6325; }","duration":"128.56578ms","start":"2026-04-08T00:44:19.221117Z","end":"2026-04-08T00:44:19.349683Z","steps":["trace[485057625] 'read index received' (duration: 31.437717ms)","trace[485057625] 'applied index is now lower than readState.Index' (duration: 97.126793ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:44:19.349862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.449412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:44:19.349949Z","caller":"traceutil/trace.go:171","msg":"trace[1063346746] range","detail":"{range_begin:/registry/jobs/openstack/keystone-rabbit-init; range_end:; response_count:0; response_revision:6108; }","duration":"243.557934ms","start":"2026-04-08T00:44:19.106381Z","end":"2026-04-08T00:44:19.349938Z","steps":["trace[1063346746] 'agreement among raft nodes before linearized reading' (duration: 243.419992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:44:19.350045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.600469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-04-08T00:44:19.350157Z","caller":"traceutil/trace.go:171","msg":"trace[1080796992] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6108; }","duration":"225.782563ms","start":"2026-04-08T00:44:19.124352Z","end":"2026-04-08T00:44:19.350135Z","steps":["trace[1080796992] 'agreement among raft nodes before linearized reading' (duration: 225.419516ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:19.693737Z","caller":"traceutil/trace.go:171","msg":"trace[636414566] transaction","detail":"{read_only:false; response_revision:6113; number_of_response:1; }","duration":"113.814344ms","start":"2026-04-08T00:44:19.579894Z","end":"2026-04-08T00:44:19.693709Z","steps":["trace[636414566] 'process raft request' (duration: 113.62163ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:19.799947Z","caller":"traceutil/trace.go:171","msg":"trace[544905418] transaction","detail":"{read_only:false; response_revision:6114; number_of_response:1; }","duration":"161.217339ms","start":"2026-04-08T00:44:19.638696Z","end":"2026-04-08T00:44:19.799913Z","steps":["trace[544905418] 'process raft request' (duration: 147.38658ms)","trace[544905418] 'compare' (duration: 13.459682ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:44:19.836421Z","caller":"traceutil/trace.go:171","msg":"trace[1626150915] transaction","detail":"{read_only:false; response_revision:6115; number_of_response:1; }","duration":"137.495495ms","start":"2026-04-08T00:44:19.698898Z","end":"2026-04-08T00:44:19.836394Z","steps":["trace[1626150915] 'process raft request' (duration: 137.092478ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:19.836512Z","caller":"traceutil/trace.go:171","msg":"trace[2067181140] transaction","detail":"{read_only:false; response_revision:6116; number_of_response:1; }","duration":"137.410814ms","start":"2026-04-08T00:44:19.699082Z","end":"2026-04-08T00:44:19.836493Z","steps":["trace[2067181140] 'process raft request' (duration: 137.143339ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:44:19.836716Z","caller":"traceutil/trace.go:171","msg":"trace[772999809] transaction","detail":"{read_only:false; response_revision:6117; number_of_response:1; }","duration":"135.206282ms","start":"2026-04-08T00:44:19.701466Z","end":"2026-04-08T00:44:19.836672Z","steps":["trace[772999809] 'process raft request' (duration: 134.879807ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:31.398907Z","caller":"traceutil/trace.go:171","msg":"trace[2132351433] transaction","detail":"{read_only:false; response_revision:7881; number_of_response:1; }","duration":"117.712ms","start":"2026-04-08T00:48:31.281137Z","end":"2026-04-08T00:48:31.398849Z","steps":["trace[2132351433] 'process raft request' (duration: 117.570508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:39.557521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.508129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-znhmh\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T00:48:39.557608Z","caller":"traceutil/trace.go:171","msg":"trace[1464407599] range","detail":"{range_begin:/registry/pods/openstack/keepalived-znhmh; range_end:; response_count:1; response_revision:8060; }","duration":"801.637661ms","start":"2026-04-08T00:48:38.755954Z","end":"2026-04-08T00:48:39.557592Z","steps":["trace[1464407599] 'range keys from in-memory index tree' (duration: 801.374647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:39.557632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:38.755936Z","time spent":"801.690012ms","remote":"127.0.0.1:48800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-znhmh\" "} {"level":"warn","ts":"2026-04-08T00:48:39.557893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.902757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:48:39.557983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.425598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:48:39.558001Z","caller":"traceutil/trace.go:171","msg":"trace[157337014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8060; }","duration":"779.06212ms","start":"2026-04-08T00:48:38.778921Z","end":"2026-04-08T00:48:39.557983Z","steps":["trace[157337014] 'range keys from in-memory index tree' (duration: 778.773644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:39.558046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:38.778901Z","time spent":"779.137012ms","remote":"127.0.0.1:48890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T00:48:39.558059Z","caller":"traceutil/trace.go:171","msg":"trace[285200754] linearizableReadLoop","detail":"{readStateIndex:8340; appliedIndex:8339; }","duration":"367.915828ms","start":"2026-04-08T00:48:39.19013Z","end":"2026-04-08T00:48:39.558046Z","steps":["trace[285200754] 'read index received' (duration: 187.252075ms)","trace[285200754] 'applied index is now lower than readState.Index' (duration: 180.662543ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:48:39.558242Z","caller":"traceutil/trace.go:171","msg":"trace[1246774466] transaction","detail":"{read_only:false; response_revision:8061; number_of_response:1; }","duration":"756.966418ms","start":"2026-04-08T00:48:38.801264Z","end":"2026-04-08T00:48:39.55823Z","steps":["trace[1246774466] 'process raft request' (duration: 576.225784ms)","trace[1246774466] 'compare' (duration: 180.063692ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:48:39.558299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:38.801244Z","time spent":"757.0249ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:48:39.558518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.328045ms","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-04-08T00:48:39.558626Z","caller":"traceutil/trace.go:171","msg":"trace[283114927] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:8061; }","duration":"368.514469ms","start":"2026-04-08T00:48:39.19009Z","end":"2026-04-08T00:48:39.558604Z","steps":["trace[283114927] 'agreement among raft nodes before linearized reading' (duration: 368.157952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:39.55868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:39.190073Z","time spent":"368.597341ms","remote":"127.0.0.1:48782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1803,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"warn","ts":"2026-04-08T00:48:39.573478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.36401ms","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-04-08T00:48:39.573553Z","caller":"traceutil/trace.go:171","msg":"trace[872112280] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8061; }","duration":"142.494182ms","start":"2026-04-08T00:48:39.431041Z","end":"2026-04-08T00:48:39.573535Z","steps":["trace[872112280] 'agreement among raft nodes before linearized reading' (duration: 142.329359ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:49.775818Z","caller":"traceutil/trace.go:171","msg":"trace[2104063845] transaction","detail":"{read_only:false; response_revision:8113; number_of_response:1; }","duration":"107.781466ms","start":"2026-04-08T00:48:49.668011Z","end":"2026-04-08T00:48:49.775792Z","steps":["trace[2104063845] 'process raft request' (duration: 101.806115ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:50.189541Z","caller":"traceutil/trace.go:171","msg":"trace[705766662] transaction","detail":"{read_only:false; response_revision:8116; number_of_response:1; }","duration":"108.194713ms","start":"2026-04-08T00:48:50.081317Z","end":"2026-04-08T00:48:50.189512Z","steps":["trace[705766662] 'process raft request' (duration: 74.258092ms)","trace[705766662] 'compare' (duration: 33.757388ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:48:50.189733Z","caller":"traceutil/trace.go:171","msg":"trace[176908797] transaction","detail":"{read_only:false; response_revision:8117; number_of_response:1; }","duration":"108.166993ms","start":"2026-04-08T00:48:50.081554Z","end":"2026-04-08T00:48:50.189721Z","steps":["trace[176908797] 'process raft request' (duration: 107.913728ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:51.138032Z","caller":"traceutil/trace.go:171","msg":"trace[941401207] transaction","detail":"{read_only:false; response_revision:8120; number_of_response:1; }","duration":"428.705519ms","start":"2026-04-08T00:48:50.709289Z","end":"2026-04-08T00:48:51.137994Z","steps":["trace[941401207] 'process raft request' (duration: 428.504556ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:51.138039Z","caller":"traceutil/trace.go:171","msg":"trace[558987194] linearizableReadLoop","detail":"{readStateIndex:8403; appliedIndex:8403; }","duration":"397.564509ms","start":"2026-04-08T00:48:50.740438Z","end":"2026-04-08T00:48:51.138002Z","steps":["trace[558987194] 'read index received' (duration: 397.546969ms)","trace[558987194] 'applied index is now lower than readState.Index' (duration: 14.06µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:48:51.138227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.757183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-04-08T00:48:51.138304Z","caller":"traceutil/trace.go:171","msg":"trace[1290821435] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8120; }","duration":"397.876436ms","start":"2026-04-08T00:48:50.74041Z","end":"2026-04-08T00:48:51.138286Z","steps":["trace[1290821435] 'agreement among raft nodes before linearized reading' (duration: 397.696732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:51.138319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:50.709253Z","time spent":"428.890054ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:48:51.138353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:50.740398Z","time spent":"397.944817ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-08T00:48:51.342098Z","caller":"traceutil/trace.go:171","msg":"trace[565468758] transaction","detail":"{read_only:false; response_revision:8121; number_of_response:1; }","duration":"251.764265ms","start":"2026-04-08T00:48:51.09029Z","end":"2026-04-08T00:48:51.342054Z","steps":["trace[565468758] 'process raft request' (duration: 181.853025ms)","trace[565468758] 'compare' (duration: 69.751438ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:48:51.37737Z","caller":"traceutil/trace.go:171","msg":"trace[411849735] transaction","detail":"{read_only:false; response_revision:8122; number_of_response:1; }","duration":"234.540496ms","start":"2026-04-08T00:48:51.14279Z","end":"2026-04-08T00:48:51.377331Z","steps":["trace[411849735] 'process raft request' (duration: 234.337912ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:51.414412Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5905} {"level":"info","ts":"2026-04-08T00:48:51.721467Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5905,"took":"304.876744ms","hash":3908351020,"current-db-size-bytes":58003456,"current-db-size":"58 MB","current-db-size-in-use-bytes":26554368,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-08T00:48:51.721605Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3908351020,"revision":5905,"compact-revision":2826} {"level":"warn","ts":"2026-04-08T00:48:51.995407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.433079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:48:51.995516Z","caller":"traceutil/trace.go:171","msg":"trace[1045779756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8124; }","duration":"216.602122ms","start":"2026-04-08T00:48:51.778892Z","end":"2026-04-08T00:48:51.995494Z","steps":["trace[1045779756] 'range keys from in-memory index tree' (duration: 216.335286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:51.995407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.138155ms","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-04-08T00:48:51.995645Z","caller":"traceutil/trace.go:171","msg":"trace[47316054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8124; }","duration":"215.417849ms","start":"2026-04-08T00:48:51.780196Z","end":"2026-04-08T00:48:51.995614Z","steps":["trace[47316054] 'range keys from in-memory index tree' (duration: 214.979062ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:52.413866Z","caller":"traceutil/trace.go:171","msg":"trace[56144199] transaction","detail":"{read_only:false; response_revision:8125; number_of_response:1; }","duration":"414.137299ms","start":"2026-04-08T00:48:51.999694Z","end":"2026-04-08T00:48:52.413831Z","steps":["trace[56144199] 'process raft request' (duration: 413.922885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:52.414158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:51.999673Z","time spent":"414.286641ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:48:52.471265Z","caller":"traceutil/trace.go:171","msg":"trace[395056299] linearizableReadLoop","detail":"{readStateIndex:8410; appliedIndex:8408; }","duration":"276.360003ms","start":"2026-04-08T00:48:52.194872Z","end":"2026-04-08T00:48:52.471232Z","steps":["trace[395056299] 'read index received' (duration: 218.791612ms)","trace[395056299] 'applied index is now lower than readState.Index' (duration: 57.566941ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:48:52.4714Z","caller":"traceutil/trace.go:171","msg":"trace[1010703965] transaction","detail":"{read_only:false; response_revision:8126; number_of_response:1; }","duration":"471.050307ms","start":"2026-04-08T00:48:52.000301Z","end":"2026-04-08T00:48:52.471352Z","steps":["trace[1010703965] 'process raft request' (duration: 470.736422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:52.471604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.670939ms","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-04-08T00:48:52.471659Z","caller":"traceutil/trace.go:171","msg":"trace[1023764262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8126; }","duration":"276.829491ms","start":"2026-04-08T00:48:52.194813Z","end":"2026-04-08T00:48:52.471642Z","steps":["trace[1023764262] 'agreement among raft nodes before linearized reading' (duration: 276.587957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:52.471661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:48:52.00028Z","time spent":"471.23336ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:48:52.471666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.602798ms","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-04-08T00:48:52.471667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.768171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T00:48:52.471755Z","caller":"traceutil/trace.go:171","msg":"trace[574595853] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8126; }","duration":"167.731742ms","start":"2026-04-08T00:48:52.303999Z","end":"2026-04-08T00:48:52.471731Z","steps":["trace[574595853] 'agreement among raft nodes before linearized reading' (duration: 167.484718ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:52.471755Z","caller":"traceutil/trace.go:171","msg":"trace[600707308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8126; }","duration":"276.941055ms","start":"2026-04-08T00:48:52.194797Z","end":"2026-04-08T00:48:52.471738Z","steps":["trace[600707308] 'agreement among raft nodes before linearized reading' (duration: 276.588409ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:52.615677Z","caller":"traceutil/trace.go:171","msg":"trace[213001368] transaction","detail":"{read_only:false; response_revision:8127; number_of_response:1; }","duration":"137.211324ms","start":"2026-04-08T00:48:52.478216Z","end":"2026-04-08T00:48:52.615428Z","steps":["trace[213001368] 'process raft request' (duration: 136.9841ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:52.652934Z","caller":"traceutil/trace.go:171","msg":"trace[1481678221] linearizableReadLoop","detail":"{readStateIndex:8412; appliedIndex:8410; }","duration":"173.085692ms","start":"2026-04-08T00:48:52.47982Z","end":"2026-04-08T00:48:52.652906Z","steps":["trace[1481678221] 'read index received' (duration: 135.413821ms)","trace[1481678221] 'applied index is now lower than readState.Index' (duration: 37.670861ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:48:52.653021Z","caller":"traceutil/trace.go:171","msg":"trace[2515022] transaction","detail":"{read_only:false; response_revision:8129; number_of_response:1; }","duration":"109.636171ms","start":"2026-04-08T00:48:52.543345Z","end":"2026-04-08T00:48:52.652981Z","steps":["trace[2515022] 'process raft request' (duration: 109.480648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:48:52.653297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.469328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19387"} {"level":"info","ts":"2026-04-08T00:48:52.653289Z","caller":"traceutil/trace.go:171","msg":"trace[97782808] transaction","detail":"{read_only:false; response_revision:8128; number_of_response:1; }","duration":"175.019787ms","start":"2026-04-08T00:48:52.47825Z","end":"2026-04-08T00:48:52.65327Z","steps":["trace[97782808] 'process raft request' (duration: 174.441967ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:52.653345Z","caller":"traceutil/trace.go:171","msg":"trace[1587813763] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8130; }","duration":"173.526619ms","start":"2026-04-08T00:48:52.479802Z","end":"2026-04-08T00:48:52.653329Z","steps":["trace[1587813763] 'agreement among raft nodes before linearized reading' (duration: 173.369667ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:53.316733Z","caller":"traceutil/trace.go:171","msg":"trace[308077561] transaction","detail":"{read_only:false; response_revision:8132; number_of_response:1; }","duration":"168.271842ms","start":"2026-04-08T00:48:53.148429Z","end":"2026-04-08T00:48:53.316701Z","steps":["trace[308077561] 'process raft request' (duration: 168.098769ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:53.520022Z","caller":"traceutil/trace.go:171","msg":"trace[1070051092] transaction","detail":"{read_only:false; response_revision:8133; number_of_response:1; }","duration":"133.170248ms","start":"2026-04-08T00:48:53.386818Z","end":"2026-04-08T00:48:53.519989Z","steps":["trace[1070051092] 'process raft request' (duration: 81.058229ms)","trace[1070051092] 'compare' (duration: 51.760663ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:48:53.814805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.985325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:48:53.814937Z","caller":"traceutil/trace.go:171","msg":"trace[21260526] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:8133; }","duration":"191.139518ms","start":"2026-04-08T00:48:53.623772Z","end":"2026-04-08T00:48:53.814912Z","steps":["trace[21260526] 'count revisions from in-memory index tree' (duration: 190.879633ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:53.815079Z","caller":"traceutil/trace.go:171","msg":"trace[91528304] linearizableReadLoop","detail":"{readStateIndex:8418; appliedIndex:8417; }","duration":"131.423816ms","start":"2026-04-08T00:48:53.683624Z","end":"2026-04-08T00:48:53.815048Z","steps":["trace[91528304] 'read index received' (duration: 122.156954ms)","trace[91528304] 'applied index is now lower than readState.Index' (duration: 9.265612ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:48:53.815382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.733662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-znhmh\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T00:48:53.815429Z","caller":"traceutil/trace.go:171","msg":"trace[1392734154] range","detail":"{range_begin:/registry/pods/openstack/keepalived-znhmh; range_end:; response_count:1; response_revision:8134; }","duration":"131.827974ms","start":"2026-04-08T00:48:53.683589Z","end":"2026-04-08T00:48:53.815417Z","steps":["trace[1392734154] 'agreement among raft nodes before linearized reading' (duration: 131.62849ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:48:53.815447Z","caller":"traceutil/trace.go:171","msg":"trace[1891408366] transaction","detail":"{read_only:false; response_revision:8134; number_of_response:1; }","duration":"135.671286ms","start":"2026-04-08T00:48:53.679454Z","end":"2026-04-08T00:48:53.815125Z","steps":["trace[1891408366] 'process raft request' (duration: 126.415563ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:49:19.56671Z","caller":"traceutil/trace.go:171","msg":"trace[431300761] transaction","detail":"{read_only:false; response_revision:8323; number_of_response:1; }","duration":"104.206168ms","start":"2026-04-08T00:49:19.462479Z","end":"2026-04-08T00:49:19.566685Z","steps":["trace[431300761] 'process raft request' (duration: 104.051686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:20.896212Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743865336149769,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T00:49:21.366279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.575716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:49:21.366547Z","caller":"traceutil/trace.go:171","msg":"trace[1069502101] linearizableReadLoop","detail":"{readStateIndex:8619; appliedIndex:8617; }","duration":"971.031043ms","start":"2026-04-08T00:49:20.395493Z","end":"2026-04-08T00:49:21.366524Z","steps":["trace[1069502101] 'read index received' (duration: 534.098721ms)","trace[1069502101] 'applied index is now lower than readState.Index' (duration: 436.930952ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:49:21.36667Z","caller":"traceutil/trace.go:171","msg":"trace[1229281788] transaction","detail":"{read_only:false; response_revision:8327; number_of_response:1; }","duration":"1.034629617s","start":"2026-04-08T00:49:20.332023Z","end":"2026-04-08T00:49:21.366652Z","steps":["trace[1229281788] 'process raft request' (duration: 597.550961ms)","trace[1229281788] 'compare' (duration: 436.069387ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:49:21.366765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:20.331983Z","time spent":"1.034732839s","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:49:21.367219Z","caller":"traceutil/trace.go:171","msg":"trace[1302045922] transaction","detail":"{read_only:false; response_revision:8328; number_of_response:1; }","duration":"486.976104ms","start":"2026-04-08T00:49:20.880226Z","end":"2026-04-08T00:49:21.367202Z","steps":["trace[1302045922] 'process raft request' (duration: 486.198799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:21.367317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:20.880206Z","time spent":"487.064605ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:49:21.367467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.971841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:49:21.36752Z","caller":"traceutil/trace.go:171","msg":"trace[963132430] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:8328; }","duration":"972.073833ms","start":"2026-04-08T00:49:20.395431Z","end":"2026-04-08T00:49:21.367505Z","steps":["trace[963132430] 'agreement among raft nodes before linearized reading' (duration: 971.986201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:21.367565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:20.395406Z","time spent":"972.144893ms","remote":"127.0.0.1:49412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-08T00:49:21.367604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.17604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-08T00:49:21.367657Z","caller":"traceutil/trace.go:171","msg":"trace[1140076142] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8328; }","duration":"867.285642ms","start":"2026-04-08T00:49:20.500355Z","end":"2026-04-08T00:49:21.367641Z","steps":["trace[1140076142] 'agreement among raft nodes before linearized reading' (duration: 867.129319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:21.367689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:20.500333Z","time spent":"867.348914ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-08T00:49:21.367836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.187924ms","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-04-08T00:49:21.36789Z","caller":"traceutil/trace.go:171","msg":"trace[1924528174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8328; }","duration":"598.242095ms","start":"2026-04-08T00:49:20.769629Z","end":"2026-04-08T00:49:21.367871Z","steps":["trace[1924528174] 'agreement among raft nodes before linearized reading' (duration: 598.097012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:21.367923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:20.769592Z","time spent":"598.324536ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T00:49:21.367926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.738381ms","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-04-08T00:49:21.368015Z","caller":"traceutil/trace.go:171","msg":"trace[833001402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8328; }","duration":"680.882524ms","start":"2026-04-08T00:49:20.68711Z","end":"2026-04-08T00:49:21.367993Z","steps":["trace[833001402] 'agreement among raft nodes before linearized reading' (duration: 680.644749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:21.368003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.831929ms","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-04-08T00:49:21.368055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:20.687089Z","time spent":"680.957695ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-08T00:49:21.368096Z","caller":"traceutil/trace.go:171","msg":"trace[866976272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8328; }","duration":"437.972942ms","start":"2026-04-08T00:49:20.930102Z","end":"2026-04-08T00:49:21.368075Z","steps":["trace[866976272] 'agreement among raft nodes before linearized reading' (duration: 437.662545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:21.368151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:20.930086Z","time spent":"438.056534ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T00:49:21.367953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.793467ms","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-04-08T00:49:21.368501Z","caller":"traceutil/trace.go:171","msg":"trace[531291275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8328; }","duration":"677.379278ms","start":"2026-04-08T00:49:20.691094Z","end":"2026-04-08T00:49:21.368473Z","steps":["trace[531291275] 'agreement among raft nodes before linearized reading' (duration: 676.644444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:21.368552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:20.691072Z","time spent":"677.46558ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T00:49:33.372853Z","caller":"traceutil/trace.go:171","msg":"trace[1950154815] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8432; }","duration":"121.967051ms","start":"2026-04-08T00:49:33.250854Z","end":"2026-04-08T00:49:33.372821Z","steps":["trace[1950154815] 'process raft request' (duration: 77.911521ms)","trace[1950154815] 'compare' (duration: 43.905847ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:49:33.37506Z","caller":"traceutil/trace.go:171","msg":"trace[1710678861] transaction","detail":"{read_only:false; response_revision:8433; number_of_response:1; }","duration":"122.887998ms","start":"2026-04-08T00:49:33.252135Z","end":"2026-04-08T00:49:33.375023Z","steps":["trace[1710678861] 'process raft request' (duration: 122.671724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:33.74185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.448667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-08T00:49:33.741964Z","caller":"traceutil/trace.go:171","msg":"trace[1073696840] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8436; }","duration":"102.593069ms","start":"2026-04-08T00:49:33.63934Z","end":"2026-04-08T00:49:33.741933Z","steps":["trace[1073696840] 'range keys from in-memory index tree' (duration: 102.284714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:33.742064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.545644ms","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-04-08T00:49:33.742187Z","caller":"traceutil/trace.go:171","msg":"trace[1645915510] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8436; }","duration":"263.715188ms","start":"2026-04-08T00:49:33.478435Z","end":"2026-04-08T00:49:33.74215Z","steps":["trace[1645915510] 'range keys from in-memory index tree' (duration: 263.3212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:33.742131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.263527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T00:49:33.74235Z","caller":"traceutil/trace.go:171","msg":"trace[1894388190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8436; }","duration":"258.516201ms","start":"2026-04-08T00:49:33.483815Z","end":"2026-04-08T00:49:33.742331Z","steps":["trace[1894388190] 'range keys from in-memory index tree' (duration: 258.109223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:33.742524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.034059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-08T00:49:33.742398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.293145ms","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-04-08T00:49:33.742579Z","caller":"traceutil/trace.go:171","msg":"trace[1145905964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8436; }","duration":"261.12407ms","start":"2026-04-08T00:49:33.481442Z","end":"2026-04-08T00:49:33.742566Z","steps":["trace[1145905964] 'range keys from in-memory index tree' (duration: 260.907657ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:49:33.742602Z","caller":"traceutil/trace.go:171","msg":"trace[1428701851] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8436; }","duration":"109.549949ms","start":"2026-04-08T00:49:33.633038Z","end":"2026-04-08T00:49:33.742588Z","steps":["trace[1428701851] 'range keys from in-memory index tree' (duration: 109.152211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:33.742671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.97014ms","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-04-08T00:49:33.742707Z","caller":"traceutil/trace.go:171","msg":"trace[470603445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8436; }","duration":"259.028171ms","start":"2026-04-08T00:49:33.483667Z","end":"2026-04-08T00:49:33.742696Z","steps":["trace[470603445] 'range keys from in-memory index tree' (duration: 258.852618ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:49:35.272737Z","caller":"traceutil/trace.go:171","msg":"trace[1928901997] transaction","detail":"{read_only:false; response_revision:8448; number_of_response:1; }","duration":"127.787779ms","start":"2026-04-08T00:49:35.144916Z","end":"2026-04-08T00:49:35.272704Z","steps":["trace[1928901997] 'process raft request' (duration: 127.405861ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:49:35.914543Z","caller":"traceutil/trace.go:171","msg":"trace[113739656] linearizableReadLoop","detail":"{readStateIndex:8747; appliedIndex:8746; }","duration":"134.526993ms","start":"2026-04-08T00:49:35.779985Z","end":"2026-04-08T00:49:35.914512Z","steps":["trace[113739656] 'read index received' (duration: 134.255989ms)","trace[113739656] 'applied index is now lower than readState.Index' (duration: 270.134µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:49:35.914662Z","caller":"traceutil/trace.go:171","msg":"trace[1954055233] transaction","detail":"{read_only:false; response_revision:8451; number_of_response:1; }","duration":"238.172002ms","start":"2026-04-08T00:49:35.676451Z","end":"2026-04-08T00:49:35.914623Z","steps":["trace[1954055233] 'process raft request' (duration: 237.870727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:35.91474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.740988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:49:35.914781Z","caller":"traceutil/trace.go:171","msg":"trace[1603568871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8451; }","duration":"134.89469ms","start":"2026-04-08T00:49:35.779873Z","end":"2026-04-08T00:49:35.914768Z","steps":["trace[1603568871] 'agreement among raft nodes before linearized reading' (duration: 134.801328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:35.915031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.518762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-08T00:49:35.91507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.740269ms","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-04-08T00:49:35.915123Z","caller":"traceutil/trace.go:171","msg":"trace[395615463] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8451; }","duration":"121.649634ms","start":"2026-04-08T00:49:35.793451Z","end":"2026-04-08T00:49:35.915101Z","steps":["trace[395615463] 'agreement among raft nodes before linearized reading' (duration: 121.356149ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:49:35.915166Z","caller":"traceutil/trace.go:171","msg":"trace[1379683237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8451; }","duration":"133.911932ms","start":"2026-04-08T00:49:35.781235Z","end":"2026-04-08T00:49:35.915147Z","steps":["trace[1379683237] 'agreement among raft nodes before linearized reading' (duration: 133.539265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:35.915276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.350027ms","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-04-08T00:49:35.915321Z","caller":"traceutil/trace.go:171","msg":"trace[1778641493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8451; }","duration":"122.419338ms","start":"2026-04-08T00:49:35.792892Z","end":"2026-04-08T00:49:35.915312Z","steps":["trace[1778641493] 'agreement among raft nodes before linearized reading' (duration: 122.264235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:35.915294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.347068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-04-08T00:49:35.915341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.624692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T00:49:35.915455Z","caller":"traceutil/trace.go:171","msg":"trace[1534941121] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8451; }","duration":"121.569412ms","start":"2026-04-08T00:49:35.793834Z","end":"2026-04-08T00:49:35.915404Z","steps":["trace[1534941121] 'agreement among raft nodes before linearized reading' (duration: 121.089113ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:49:35.915457Z","caller":"traceutil/trace.go:171","msg":"trace[2079723084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8451; }","duration":"121.785517ms","start":"2026-04-08T00:49:35.793643Z","end":"2026-04-08T00:49:35.915428Z","steps":["trace[2079723084] 'agreement among raft nodes before linearized reading' (duration: 121.429199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:35.915573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.703544ms","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-04-08T00:49:35.915639Z","caller":"traceutil/trace.go:171","msg":"trace[1120560979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8451; }","duration":"122.799875ms","start":"2026-04-08T00:49:35.792824Z","end":"2026-04-08T00:49:35.915624Z","steps":["trace[1120560979] 'agreement among raft nodes before linearized reading' (duration: 122.435978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:36.719287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.634621ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:49:36.719439Z","caller":"traceutil/trace.go:171","msg":"trace[1701233051] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8452; }","duration":"505.810434ms","start":"2026-04-08T00:49:36.213607Z","end":"2026-04-08T00:49:36.719417Z","steps":["trace[1701233051] 'range keys from in-memory index tree' (duration: 505.61055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:36.72019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.868066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:49:36.720496Z","caller":"traceutil/trace.go:171","msg":"trace[1197451919] transaction","detail":"{read_only:false; response_revision:8453; number_of_response:1; }","duration":"800.50922ms","start":"2026-04-08T00:49:35.919933Z","end":"2026-04-08T00:49:36.720442Z","steps":["trace[1197451919] 'process raft request' (duration: 135.275528ms)","trace[1197451919] 'compare' (duration: 664.095641ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:49:36.720611Z","caller":"traceutil/trace.go:171","msg":"trace[251979924] transaction","detail":"{read_only:false; response_revision:8458; number_of_response:1; }","duration":"659.390863ms","start":"2026-04-08T00:49:36.061202Z","end":"2026-04-08T00:49:36.720593Z","steps":["trace[251979924] 'process raft request' (duration: 659.349672ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:49:36.720636Z","caller":"traceutil/trace.go:171","msg":"trace[928820724] transaction","detail":"{read_only:false; response_revision:8455; number_of_response:1; }","duration":"800.414408ms","start":"2026-04-08T00:49:35.92018Z","end":"2026-04-08T00:49:36.720594Z","steps":["trace[928820724] 'process raft request' (duration: 800.250585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:36.720691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:35.919919Z","time spent":"800.683053ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:49:36.720698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:36.061184Z","time spent":"659.474154ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:49:36.720711Z","caller":"traceutil/trace.go:171","msg":"trace[1405216476] transaction","detail":"{read_only:false; response_revision:8454; number_of_response:1; }","duration":"800.617001ms","start":"2026-04-08T00:49:35.920048Z","end":"2026-04-08T00:49:36.720665Z","steps":["trace[1405216476] 'process raft request' (duration: 800.282144ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:49:36.720737Z","caller":"traceutil/trace.go:171","msg":"trace[1813030967] linearizableReadLoop","detail":"{readStateIndex:8753; appliedIndex:8748; }","duration":"669.494861ms","start":"2026-04-08T00:49:36.051212Z","end":"2026-04-08T00:49:36.720707Z","steps":["trace[1813030967] 'read index received' (duration: 4.054855ms)","trace[1813030967] 'applied index is now lower than readState.Index' (duration: 665.436276ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:49:36.720921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:35.920009Z","time spent":"800.810485ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:49:36.720756Z","caller":"traceutil/trace.go:171","msg":"trace[450290235] transaction","detail":"{read_only:false; response_revision:8456; number_of_response:1; }","duration":"800.111912ms","start":"2026-04-08T00:49:35.92062Z","end":"2026-04-08T00:49:36.720732Z","steps":["trace[450290235] 'process raft request' (duration: 799.853898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:36.721024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.807796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-04-08T00:49:36.72111Z","caller":"traceutil/trace.go:171","msg":"trace[1513819039] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:8458; }","duration":"669.93855ms","start":"2026-04-08T00:49:36.051144Z","end":"2026-04-08T00:49:36.721083Z","steps":["trace[1513819039] 'agreement among raft nodes before linearized reading' (duration: 669.641774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:36.721122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:35.920607Z","time spent":"800.461169ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:49:36.721166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:36.051119Z","time spent":"670.033992ms","remote":"127.0.0.1:48810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2010,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-04-08T00:49:36.721273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:35.920159Z","time spent":"800.664232ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:49:36.721812Z","caller":"traceutil/trace.go:171","msg":"trace[1348451362] transaction","detail":"{read_only:false; response_revision:8457; number_of_response:1; }","duration":"801.07286ms","start":"2026-04-08T00:49:35.920698Z","end":"2026-04-08T00:49:36.721771Z","steps":["trace[1348451362] 'process raft request' (duration: 799.809527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:36.721942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:35.92069Z","time spent":"801.190663ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:49:36.731558Z","caller":"traceutil/trace.go:171","msg":"trace[2085081326] transaction","detail":"{read_only:false; response_revision:8459; number_of_response:1; }","duration":"207.016882ms","start":"2026-04-08T00:49:36.524523Z","end":"2026-04-08T00:49:36.73154Z","steps":["trace[2085081326] 'process raft request' (duration: 206.89572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:36.731739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.34684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-08T00:49:36.731788Z","caller":"traceutil/trace.go:171","msg":"trace[1846164663] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8459; }","duration":"632.444852ms","start":"2026-04-08T00:49:36.099331Z","end":"2026-04-08T00:49:36.731776Z","steps":["trace[1846164663] 'agreement among raft nodes before linearized reading' (duration: 632.31169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:36.731812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:36.099308Z","time spent":"632.497723ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-08T00:49:36.731972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.763604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-08T00:49:36.732Z","caller":"traceutil/trace.go:171","msg":"trace[289588328] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8459; }","duration":"296.827005ms","start":"2026-04-08T00:49:36.435166Z","end":"2026-04-08T00:49:36.731993Z","steps":["trace[289588328] 'agreement among raft nodes before linearized reading' (duration: 296.741004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:36.732237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.825439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-znhmh\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T00:49:36.732384Z","caller":"traceutil/trace.go:171","msg":"trace[1128673985] range","detail":"{range_begin:/registry/pods/openstack/keepalived-znhmh; range_end:; response_count:1; response_revision:8459; }","duration":"626.009332ms","start":"2026-04-08T00:49:36.106357Z","end":"2026-04-08T00:49:36.732366Z","steps":["trace[1128673985] 'agreement among raft nodes before linearized reading' (duration: 625.588944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:49:36.7325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:49:36.106346Z","time spent":"626.143044ms","remote":"127.0.0.1:48800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-znhmh\" "} {"level":"info","ts":"2026-04-08T00:49:37.684408Z","caller":"traceutil/trace.go:171","msg":"trace[844477942] transaction","detail":"{read_only:false; response_revision:8465; number_of_response:1; }","duration":"198.003425ms","start":"2026-04-08T00:49:37.486376Z","end":"2026-04-08T00:49:37.68438Z","steps":["trace[844477942] 'process raft request' (duration: 197.822492ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:50:23.997225Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002430.wal"} {"level":"info","ts":"2026-04-08T00:50:42.573867Z","caller":"traceutil/trace.go:171","msg":"trace[627120528] transaction","detail":"{read_only:false; response_revision:9077; number_of_response:1; }","duration":"270.527867ms","start":"2026-04-08T00:50:42.303315Z","end":"2026-04-08T00:50:42.573843Z","steps":["trace[627120528] 'process raft request' (duration: 270.379004ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:50:43.18631Z","caller":"traceutil/trace.go:171","msg":"trace[984121246] transaction","detail":"{read_only:false; response_revision:9082; number_of_response:1; }","duration":"117.10456ms","start":"2026-04-08T00:50:43.069181Z","end":"2026-04-08T00:50:43.186286Z","steps":["trace[984121246] 'process raft request' (duration: 116.950518ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:50:43.186508Z","caller":"traceutil/trace.go:171","msg":"trace[573813260] linearizableReadLoop","detail":"{readStateIndex:9396; appliedIndex:9393; }","duration":"115.481321ms","start":"2026-04-08T00:50:43.071004Z","end":"2026-04-08T00:50:43.186485Z","steps":["trace[573813260] 'read index received' (duration: 15.789735ms)","trace[573813260] 'applied index is now lower than readState.Index' (duration: 99.690386ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:50:43.18672Z","caller":"traceutil/trace.go:171","msg":"trace[1010121007] transaction","detail":"{read_only:false; response_revision:9083; number_of_response:1; }","duration":"117.227753ms","start":"2026-04-08T00:50:43.069479Z","end":"2026-04-08T00:50:43.186706Z","steps":["trace[1010121007] 'process raft request' (duration: 116.772745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:43.186976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.945799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-08T00:50:43.187949Z","caller":"traceutil/trace.go:171","msg":"trace[1653701745] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:9083; }","duration":"116.938418ms","start":"2026-04-08T00:50:43.070986Z","end":"2026-04-08T00:50:43.187924Z","steps":["trace[1653701745] 'agreement among raft nodes before linearized reading' (duration: 115.762216ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:50:43.314154Z","caller":"traceutil/trace.go:171","msg":"trace[299794728] linearizableReadLoop","detail":"{readStateIndex:9398; appliedIndex:9396; }","duration":"111.443786ms","start":"2026-04-08T00:50:43.202656Z","end":"2026-04-08T00:50:43.3141Z","steps":["trace[299794728] 'read index received' (duration: 23.232003ms)","trace[299794728] 'applied index is now lower than readState.Index' (duration: 88.210343ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:50:43.314355Z","caller":"traceutil/trace.go:171","msg":"trace[1235269653] transaction","detail":"{read_only:false; response_revision:9085; number_of_response:1; }","duration":"121.212618ms","start":"2026-04-08T00:50:43.193116Z","end":"2026-04-08T00:50:43.314328Z","steps":["trace[1235269653] 'process raft request' (duration: 61.850143ms)","trace[1235269653] 'compare' (duration: 59.011078ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:50:43.314586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.726997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:50:43.31467Z","caller":"traceutil/trace.go:171","msg":"trace[275553788] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:9085; }","duration":"106.846189ms","start":"2026-04-08T00:50:43.207805Z","end":"2026-04-08T00:50:43.314651Z","steps":["trace[275553788] 'agreement among raft nodes before linearized reading' (duration: 106.698546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:50:43.314794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.089247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7252"} {"level":"info","ts":"2026-04-08T00:50:43.314847Z","caller":"traceutil/trace.go:171","msg":"trace[94380629] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:9085; }","duration":"112.193699ms","start":"2026-04-08T00:50:43.202641Z","end":"2026-04-08T00:50:43.314835Z","steps":["trace[94380629] 'agreement among raft nodes before linearized reading' (duration: 111.905854ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:51:54.071171Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-08T00:51:54.076946Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-08T00:51:54.077053Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-08T00:53:04.058895Z","caller":"traceutil/trace.go:171","msg":"trace[2026710442] transaction","detail":"{read_only:false; response_revision:10237; number_of_response:1; }","duration":"116.466541ms","start":"2026-04-08T00:53:03.942375Z","end":"2026-04-08T00:53:04.058842Z","steps":["trace[2026710442] 'process raft request' (duration: 108.934742ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:53:04.058927Z","caller":"traceutil/trace.go:171","msg":"trace[79974199] transaction","detail":"{read_only:false; response_revision:10238; number_of_response:1; }","duration":"115.286729ms","start":"2026-04-08T00:53:03.943606Z","end":"2026-04-08T00:53:04.058893Z","steps":["trace[79974199] 'process raft request' (duration: 115.107856ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:53:51.420616Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8121} {"level":"info","ts":"2026-04-08T00:53:51.489974Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8121,"took":"66.788809ms","hash":2181149308,"current-db-size-bytes":58003456,"current-db-size":"58 MB","current-db-size-in-use-bytes":29122560,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-08T00:53:51.490091Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2181149308,"revision":8121,"compact-revision":5905} {"level":"info","ts":"2026-04-08T00:58:51.427278Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10541} {"level":"info","ts":"2026-04-08T00:58:51.508867Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10541,"took":"79.923442ms","hash":3752296803,"current-db-size-bytes":58003456,"current-db-size":"58 MB","current-db-size-in-use-bytes":20074496,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-08T00:58:51.508944Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3752296803,"revision":10541,"compact-revision":8121}