{"level":"warn","ts":"2026-06-19T09:11:34.733041Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-19T09:11:34.733144Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.250:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.250:2380","--initial-cluster=instance=https://199.19.213.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.250:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-19T09:11:34.733213Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-19T09:11:34.733225Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.250:2380"]} {"level":"info","ts":"2026-06-19T09:11:34.733243Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-19T09:11:34.733791Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.250:2379"]} {"level":"info","ts":"2026-06-19T09:11:34.733866Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.250:2380"],"listen-peer-urls":["https://199.19.213.250:2380"],"advertise-client-urls":["https://199.19.213.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.250:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-19T09:11:34.738516Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.436875ms"} {"level":"info","ts":"2026-06-19T09:11:34.749825Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1918dd9317ae83a2","cluster-id":"1b9b645507bfe47a"} {"level":"info","ts":"2026-06-19T09:11:34.749936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 switched to configuration voters=()"} {"level":"info","ts":"2026-06-19T09:11:34.749959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 became follower at term 0"} {"level":"info","ts":"2026-06-19T09:11:34.749975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1918dd9317ae83a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-19T09:11:34.749989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 became follower at term 1"} {"level":"info","ts":"2026-06-19T09:11:34.750056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 switched to configuration voters=(1808438874216498082)"} {"level":"warn","ts":"2026-06-19T09:11:34.755491Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-19T09:11:34.757695Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-19T09:11:34.759255Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-19T09:11:34.761562Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1918dd9317ae83a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-19T09:11:34.761696Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1918dd9317ae83a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-19T09:11:34.76178Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-19T09:11:34.761856Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-19T09:11:34.76189Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-19T09:11:34.762639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 switched to configuration voters=(1808438874216498082)"} {"level":"info","ts":"2026-06-19T09:11:34.7628Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1b9b645507bfe47a","local-member-id":"1918dd9317ae83a2","added-peer-id":"1918dd9317ae83a2","added-peer-peer-urls":["https://199.19.213.250:2380"]} {"level":"info","ts":"2026-06-19T09:11:34.76577Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-19T09:11:34.765872Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.250:2380"} {"level":"info","ts":"2026-06-19T09:11:34.76593Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.250:2380"} {"level":"info","ts":"2026-06-19T09:11:34.766053Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1918dd9317ae83a2","initial-advertise-peer-urls":["https://199.19.213.250:2380"],"listen-peer-urls":["https://199.19.213.250:2380"],"advertise-client-urls":["https://199.19.213.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-19T09:11:34.766095Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-19T09:11:35.550508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 is starting a new election at term 1"} {"level":"info","ts":"2026-06-19T09:11:35.550574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-19T09:11:35.55062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 received MsgPreVoteResp from 1918dd9317ae83a2 at term 1"} {"level":"info","ts":"2026-06-19T09:11:35.55064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 became candidate at term 2"} {"level":"info","ts":"2026-06-19T09:11:35.550652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 received MsgVoteResp from 1918dd9317ae83a2 at term 2"} {"level":"info","ts":"2026-06-19T09:11:35.550667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1918dd9317ae83a2 became leader at term 2"} {"level":"info","ts":"2026-06-19T09:11:35.550681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1918dd9317ae83a2 elected leader 1918dd9317ae83a2 at term 2"} {"level":"info","ts":"2026-06-19T09:11:35.55364Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T09:11:35.553865Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1918dd9317ae83a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.250:2379]}","request-path":"/0/members/1918dd9317ae83a2/attributes","cluster-id":"1b9b645507bfe47a","publish-timeout":"7s"} {"level":"info","ts":"2026-06-19T09:11:35.553879Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-19T09:11:35.553873Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-19T09:11:35.554178Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-19T09:11:35.554244Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-19T09:11:35.555384Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1b9b645507bfe47a","local-member-id":"1918dd9317ae83a2","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T09:11:35.55557Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.250:2379"} {"level":"info","ts":"2026-06-19T09:11:35.555693Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T09:11:35.555819Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T09:11:35.555968Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-19T09:11:51.116249Z","caller":"traceutil/trace.go:171","msg":"trace[1148843377] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"139.572437ms","start":"2026-06-19T09:11:50.976588Z","end":"2026-06-19T09:11:51.11616Z","steps":["trace[1148843377] 'process raft request' (duration: 105.994096ms)","trace[1148843377] 'compare' (duration: 33.357956ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T09:11:51.431052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.148817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T09:11:51.431221Z","caller":"traceutil/trace.go:171","msg":"trace[1000057294] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"311.431921ms","start":"2026-06-19T09:11:51.119763Z","end":"2026-06-19T09:11:51.431195Z","steps":["trace[1000057294] 'process raft request' (duration: 182.408689ms)","trace[1000057294] 'compare' (duration: 127.908071ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T09:11:51.431322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T09:11:51.119742Z","time spent":"311.535013ms","remote":"127.0.0.1:46730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T09:11:51.605234Z","caller":"traceutil/trace.go:171","msg":"trace[42966663] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"160.641696ms","start":"2026-06-19T09:11:51.444557Z","end":"2026-06-19T09:11:51.605199Z","steps":["trace[42966663] 'process raft request' (duration: 111.562904ms)","trace[42966663] 'compare' (duration: 48.939129ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:12:13.696368Z","caller":"traceutil/trace.go:171","msg":"trace[1724052324] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"114.685495ms","start":"2026-06-19T09:12:13.581657Z","end":"2026-06-19T09:12:13.696342Z","steps":["trace[1724052324] 'process raft request' (duration: 114.624134ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:12:13.696679Z","caller":"traceutil/trace.go:171","msg":"trace[1694331099] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"113.095144ms","start":"2026-06-19T09:12:13.583574Z","end":"2026-06-19T09:12:13.696669Z","steps":["trace[1694331099] 'process raft request' (duration: 112.966961ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:12:13.696754Z","caller":"traceutil/trace.go:171","msg":"trace[1951401849] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"114.029012ms","start":"2026-06-19T09:12:13.582717Z","end":"2026-06-19T09:12:13.696746Z","steps":["trace[1951401849] 'process raft request' (duration: 113.596924ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:12:13.696872Z","caller":"traceutil/trace.go:171","msg":"trace[2143683710] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"112.567603ms","start":"2026-06-19T09:12:13.58429Z","end":"2026-06-19T09:12:13.696858Z","steps":["trace[2143683710] 'process raft request' (duration: 112.321639ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:12:13.696929Z","caller":"traceutil/trace.go:171","msg":"trace[922786453] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"112.522733ms","start":"2026-06-19T09:12:13.584395Z","end":"2026-06-19T09:12:13.696918Z","steps":["trace[922786453] 'process raft request' (duration: 112.263388ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:12:13.696949Z","caller":"traceutil/trace.go:171","msg":"trace[2052795844] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"105.137077ms","start":"2026-06-19T09:12:13.591801Z","end":"2026-06-19T09:12:13.696938Z","steps":["trace[2052795844] 'process raft request' (duration: 105.097076ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:12:13.697048Z","caller":"traceutil/trace.go:171","msg":"trace[1010619765] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"107.172847ms","start":"2026-06-19T09:12:13.589869Z","end":"2026-06-19T09:12:13.697042Z","steps":["trace[1010619765] 'process raft request' (duration: 106.973853ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:12:13.697089Z","caller":"traceutil/trace.go:171","msg":"trace[287132638] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"111.292639ms","start":"2026-06-19T09:12:13.585787Z","end":"2026-06-19T09:12:13.69708Z","steps":["trace[287132638] 'process raft request' (duration: 110.997663ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:12:13.697253Z","caller":"traceutil/trace.go:171","msg":"trace[1163507095] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"116.842387ms","start":"2026-06-19T09:12:13.580391Z","end":"2026-06-19T09:12:13.697233Z","steps":["trace[1163507095] 'process raft request' (duration: 115.709365ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:12:13.870295Z","caller":"traceutil/trace.go:171","msg":"trace[1154880875] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"107.31283ms","start":"2026-06-19T09:12:13.762955Z","end":"2026-06-19T09:12:13.870267Z","steps":["trace[1154880875] 'process raft request' (duration: 32.700613ms)","trace[1154880875] 'compare' (duration: 74.418954ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:12:13.870961Z","caller":"traceutil/trace.go:171","msg":"trace[2111593622] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"107.881781ms","start":"2026-06-19T09:12:13.763034Z","end":"2026-06-19T09:12:13.870915Z","steps":["trace[2111593622] 'process raft request' (duration: 107.170837ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:12:13.871085Z","caller":"traceutil/trace.go:171","msg":"trace[1565053027] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"107.966163ms","start":"2026-06-19T09:12:13.763096Z","end":"2026-06-19T09:12:13.871062Z","steps":["trace[1565053027] 'process raft request' (duration: 107.671967ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:12:16.735111Z","caller":"traceutil/trace.go:171","msg":"trace[2128280964] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"112.202576ms","start":"2026-06-19T09:12:16.622886Z","end":"2026-06-19T09:12:16.735089Z","steps":["trace[2128280964] 'process raft request' (duration: 109.724627ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:12:19.968926Z","caller":"traceutil/trace.go:171","msg":"trace[1687844078] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"216.816692ms","start":"2026-06-19T09:12:19.752083Z","end":"2026-06-19T09:12:19.9689Z","steps":["trace[1687844078] 'process raft request' (duration: 216.67809ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:12:19.975643Z","caller":"traceutil/trace.go:171","msg":"trace[323412831] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"172.637544ms","start":"2026-06-19T09:12:19.802983Z","end":"2026-06-19T09:12:19.97562Z","steps":["trace[323412831] 'process raft request' (duration: 172.499352ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:12:20.508825Z","caller":"traceutil/trace.go:171","msg":"trace[1606991053] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"285.964082ms","start":"2026-06-19T09:12:20.222833Z","end":"2026-06-19T09:12:20.508797Z","steps":["trace[1606991053] 'process raft request' (duration: 285.281949ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:13:46.706563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.718525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T09:13:46.706685Z","caller":"traceutil/trace.go:171","msg":"trace[1751946630] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1933; }","duration":"229.355873ms","start":"2026-06-19T09:13:46.477313Z","end":"2026-06-19T09:13:46.706669Z","steps":["trace[1751946630] 'read index received' (duration: 60.971µs)","trace[1751946630] 'applied index is now lower than readState.Index' (duration: 229.293522ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:13:46.706718Z","caller":"traceutil/trace.go:171","msg":"trace[1301516815] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"293.984915ms","start":"2026-06-19T09:13:46.412705Z","end":"2026-06-19T09:13:46.70669Z","steps":["trace[1301516815] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:420; } (duration: 285.428049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:13:46.70685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.532827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-19T09:13:46.707003Z","caller":"traceutil/trace.go:171","msg":"trace[500190801] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1864; }","duration":"229.72795ms","start":"2026-06-19T09:13:46.477252Z","end":"2026-06-19T09:13:46.70698Z","steps":["trace[500190801] 'agreement among raft nodes before linearized reading' (duration: 229.460145ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:13:55.425617Z","caller":"traceutil/trace.go:171","msg":"trace[1806682005] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"123.501372ms","start":"2026-06-19T09:13:55.30208Z","end":"2026-06-19T09:13:55.425582Z","steps":["trace[1806682005] 'process raft request' (duration: 123.240598ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:13:55.451981Z","caller":"traceutil/trace.go:171","msg":"trace[2059437645] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"135.257941ms","start":"2026-06-19T09:13:55.316701Z","end":"2026-06-19T09:13:55.451959Z","steps":["trace[2059437645] 'process raft request' (duration: 135.097798ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:14:31.650989Z","caller":"traceutil/trace.go:171","msg":"trace[273096004] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"102.628629ms","start":"2026-06-19T09:14:31.548332Z","end":"2026-06-19T09:14:31.65096Z","steps":["trace[273096004] 'process raft request' (duration: 102.452735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:14:37.981084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.050068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T09:14:37.981256Z","caller":"traceutil/trace.go:171","msg":"trace[700368239] linearizableReadLoop","detail":"{readStateIndex:2269; appliedIndex:2267; }","duration":"185.424978ms","start":"2026-06-19T09:14:37.795817Z","end":"2026-06-19T09:14:37.981242Z","steps":["trace[700368239] 'read index received' (duration: 10.120965ms)","trace[700368239] 'applied index is now lower than readState.Index' (duration: 175.303123ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:14:37.981346Z","caller":"traceutil/trace.go:171","msg":"trace[1610747354] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"306.056293ms","start":"2026-06-19T09:14:37.675272Z","end":"2026-06-19T09:14:37.981328Z","steps":["trace[1610747354] 'process raft request' (duration: 305.90219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:14:37.981404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T09:14:37.675245Z","time spent":"306.125734ms","remote":"127.0.0.1:46730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T09:14:37.981492Z","caller":"traceutil/trace.go:171","msg":"trace[1129295270] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"308.318376ms","start":"2026-06-19T09:14:37.673157Z","end":"2026-06-19T09:14:37.981476Z","steps":["trace[1129295270] 'process raft request' (duration: 132.800439ms)","trace[1129295270] 'compare' (duration: 174.949477ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T09:14:37.981554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T09:14:37.673131Z","time spent":"308.389178ms","remote":"127.0.0.1:46730","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-06-19T09:14:37.981596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.962218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T09:14:37.981628Z","caller":"traceutil/trace.go:171","msg":"trace[2111405666] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:2189; }","duration":"186.032059ms","start":"2026-06-19T09:14:37.795586Z","end":"2026-06-19T09:14:37.981618Z","steps":["trace[2111405666] 'agreement among raft nodes before linearized reading' (duration: 185.967738ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:14:38.128244Z","caller":"traceutil/trace.go:171","msg":"trace[1945328482] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"142.139298ms","start":"2026-06-19T09:14:37.986028Z","end":"2026-06-19T09:14:38.128167Z","steps":["trace[1945328482] 'process raft request' (duration: 124.54433ms)","trace[1945328482] 'compare' (duration: 17.459416ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T09:14:38.454979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.879903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T09:14:38.455243Z","caller":"traceutil/trace.go:171","msg":"trace[116370787] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"468.401529ms","start":"2026-06-19T09:14:37.986795Z","end":"2026-06-19T09:14:38.455196Z","steps":["trace[116370787] 'process raft request' (duration: 252.181919ms)","trace[116370787] 'compare' (duration: 215.464946ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:14:38.455128Z","caller":"traceutil/trace.go:171","msg":"trace[1522839903] linearizableReadLoop","detail":"{readStateIndex:2271; appliedIndex:2270; }","duration":"178.77045ms","start":"2026-06-19T09:14:38.276335Z","end":"2026-06-19T09:14:38.455105Z","steps":["trace[1522839903] 'read index received' (duration: 71.451µs)","trace[1522839903] 'applied index is now lower than readState.Index' (duration: 178.696189ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T09:14:38.455512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T09:14:37.986773Z","time spent":"468.574453ms","remote":"127.0.0.1:46730","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-06-19T09:14:38.455639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.291141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-19T09:14:38.455737Z","caller":"traceutil/trace.go:171","msg":"trace[1970956344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2191; }","duration":"179.431954ms","start":"2026-06-19T09:14:38.276283Z","end":"2026-06-19T09:14:38.455715Z","steps":["trace[1970956344] 'agreement among raft nodes before linearized reading' (duration: 179.196588ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:14:38.455761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.045794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-19T09:14:38.455849Z","caller":"traceutil/trace.go:171","msg":"trace[835182634] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2191; }","duration":"158.174396ms","start":"2026-06-19T09:14:38.297652Z","end":"2026-06-19T09:14:38.455826Z","steps":["trace[835182634] 'agreement among raft nodes before linearized reading' (duration: 157.902421ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:16:02.385819Z","caller":"traceutil/trace.go:171","msg":"trace[1781426586] transaction","detail":"{read_only:false; response_revision:2839; number_of_response:1; }","duration":"111.451574ms","start":"2026-06-19T09:16:02.274345Z","end":"2026-06-19T09:16:02.385796Z","steps":["trace[1781426586] 'process raft request' (duration: 111.305992ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:16:02.389076Z","caller":"traceutil/trace.go:171","msg":"trace[947809473] transaction","detail":"{read_only:false; response_revision:2840; number_of_response:1; }","duration":"112.203639ms","start":"2026-06-19T09:16:02.27685Z","end":"2026-06-19T09:16:02.389054Z","steps":["trace[947809473] 'process raft request' (duration: 112.065416ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:19:57.216794Z","caller":"traceutil/trace.go:171","msg":"trace[174972080] transaction","detail":"{read_only:false; response_revision:4838; number_of_response:1; }","duration":"116.693273ms","start":"2026-06-19T09:19:57.100075Z","end":"2026-06-19T09:19:57.216768Z","steps":["trace[174972080] 'process raft request' (duration: 33.485074ms)","trace[174972080] 'compare' (duration: 82.957235ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:19:57.343119Z","caller":"traceutil/trace.go:171","msg":"trace[742288680] linearizableReadLoop","detail":"{readStateIndex:5022; appliedIndex:5021; }","duration":"106.166901ms","start":"2026-06-19T09:19:57.236927Z","end":"2026-06-19T09:19:57.343094Z","steps":["trace[742288680] 'read index received' (duration: 106.030969ms)","trace[742288680] 'applied index is now lower than readState.Index' (duration: 134.812µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:19:57.343185Z","caller":"traceutil/trace.go:171","msg":"trace[1286588162] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"122.518576ms","start":"2026-06-19T09:19:57.220625Z","end":"2026-06-19T09:19:57.343144Z","steps":["trace[1286588162] 'process raft request' (duration: 122.302471ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:19:57.343295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.349615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T09:19:57.343338Z","caller":"traceutil/trace.go:171","msg":"trace[1157691200] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:4839; }","duration":"106.414627ms","start":"2026-06-19T09:19:57.236912Z","end":"2026-06-19T09:19:57.343327Z","steps":["trace[1157691200] 'agreement among raft nodes before linearized reading' (duration: 106.305085ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:19:59.493072Z","caller":"traceutil/trace.go:171","msg":"trace[1219929462] transaction","detail":"{read_only:false; response_revision:4884; number_of_response:1; }","duration":"157.763114ms","start":"2026-06-19T09:19:59.335288Z","end":"2026-06-19T09:19:59.493051Z","steps":["trace[1219929462] 'process raft request' (duration: 157.642271ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:19:59.493473Z","caller":"traceutil/trace.go:171","msg":"trace[147098286] linearizableReadLoop","detail":"{readStateIndex:5070; appliedIndex:5070; }","duration":"156.091822ms","start":"2026-06-19T09:19:59.33737Z","end":"2026-06-19T09:19:59.493462Z","steps":["trace[147098286] 'read index received' (duration: 156.087332ms)","trace[147098286] 'applied index is now lower than readState.Index' (duration: 3.54µs)"],"step_count":2} {"level":"warn","ts":"2026-06-19T09:19:59.49358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.194064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T09:19:59.493619Z","caller":"traceutil/trace.go:171","msg":"trace[1358512759] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4884; }","duration":"156.272185ms","start":"2026-06-19T09:19:59.337335Z","end":"2026-06-19T09:19:59.493607Z","steps":["trace[1358512759] 'agreement among raft nodes before linearized reading' (duration: 156.175333ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:19:59.517016Z","caller":"traceutil/trace.go:171","msg":"trace[499938531] transaction","detail":"{read_only:false; response_revision:4885; number_of_response:1; }","duration":"155.454059ms","start":"2026-06-19T09:19:59.361544Z","end":"2026-06-19T09:19:59.516998Z","steps":["trace[499938531] 'process raft request' (duration: 155.296045ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:19:59.517249Z","caller":"traceutil/trace.go:171","msg":"trace[1939143319] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"153.223867ms","start":"2026-06-19T09:19:59.36401Z","end":"2026-06-19T09:19:59.517234Z","steps":["trace[1939143319] 'process raft request' (duration: 152.92816ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:20:00.689935Z","caller":"traceutil/trace.go:171","msg":"trace[1769680867] linearizableReadLoop","detail":"{readStateIndex:5102; appliedIndex:5101; }","duration":"108.077388ms","start":"2026-06-19T09:20:00.581838Z","end":"2026-06-19T09:20:00.689915Z","steps":["trace[1769680867] 'read index received' (duration: 106.56699ms)","trace[1769680867] 'applied index is now lower than readState.Index' (duration: 1.509608ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:20:00.690036Z","caller":"traceutil/trace.go:171","msg":"trace[1501842185] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"113.756207ms","start":"2026-06-19T09:20:00.576246Z","end":"2026-06-19T09:20:00.690002Z","steps":["trace[1501842185] 'process raft request' (duration: 112.215878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:20:00.690211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.342343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-06-19T09:20:00.690351Z","caller":"traceutil/trace.go:171","msg":"trace[13302721] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4914; }","duration":"108.506616ms","start":"2026-06-19T09:20:00.581818Z","end":"2026-06-19T09:20:00.690324Z","steps":["trace[13302721] 'agreement among raft nodes before linearized reading' (duration: 108.19932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:20:02.381437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.006741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-6p88h.18ba70f4fdeaa6c3\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-06-19T09:20:02.381485Z","caller":"traceutil/trace.go:171","msg":"trace[1955035541] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"243.200027ms","start":"2026-06-19T09:20:02.138258Z","end":"2026-06-19T09:20:02.381458Z","steps":["trace[1955035541] 'process raft request' (duration: 174.785712ms)","trace[1955035541] 'compare' (duration: 68.090609ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:20:02.381491Z","caller":"traceutil/trace.go:171","msg":"trace[1494858783] linearizableReadLoop","detail":"{readStateIndex:5215; appliedIndex:5214; }","duration":"176.514314ms","start":"2026-06-19T09:20:02.204941Z","end":"2026-06-19T09:20:02.381456Z","steps":["trace[1494858783] 'read index received' (duration: 108.000847ms)","trace[1494858783] 'applied index is now lower than readState.Index' (duration: 68.511597ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:20:02.381549Z","caller":"traceutil/trace.go:171","msg":"trace[1435242915] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-6p88h.18ba70f4fdeaa6c3; range_end:; response_count:1; response_revision:5024; }","duration":"245.207545ms","start":"2026-06-19T09:20:02.136319Z","end":"2026-06-19T09:20:02.381527Z","steps":["trace[1435242915] 'range keys from in-memory index tree' (duration: 244.857719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:20:02.381742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.561015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-19T09:20:02.381773Z","caller":"traceutil/trace.go:171","msg":"trace[1798463719] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5025; }","duration":"176.647267ms","start":"2026-06-19T09:20:02.205119Z","end":"2026-06-19T09:20:02.381767Z","steps":["trace[1798463719] 'agreement among raft nodes before linearized reading' (duration: 176.510605ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:20:02.381768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.81034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-19T09:20:02.381806Z","caller":"traceutil/trace.go:171","msg":"trace[175713962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5025; }","duration":"176.890122ms","start":"2026-06-19T09:20:02.204904Z","end":"2026-06-19T09:20:02.381795Z","steps":["trace[175713962] 'agreement among raft nodes before linearized reading' (duration: 176.711268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:20:02.381914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.700891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-19T09:20:02.38195Z","caller":"traceutil/trace.go:171","msg":"trace[1855189469] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5025; }","duration":"172.778842ms","start":"2026-06-19T09:20:02.209161Z","end":"2026-06-19T09:20:02.381939Z","steps":["trace[1855189469] 'agreement among raft nodes before linearized reading' (duration: 172.67118ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:20:04.574453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.496776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-19T09:20:04.574512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.032846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-19T09:20:04.574552Z","caller":"traceutil/trace.go:171","msg":"trace[825718279] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5057; }","duration":"150.118157ms","start":"2026-06-19T09:20:04.424421Z","end":"2026-06-19T09:20:04.574539Z","steps":["trace[825718279] 'range keys from in-memory index tree' (duration: 149.872913ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:20:04.574547Z","caller":"traceutil/trace.go:171","msg":"trace[400284943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5057; }","duration":"149.580957ms","start":"2026-06-19T09:20:04.424944Z","end":"2026-06-19T09:20:04.574525Z","steps":["trace[400284943] 'range keys from in-memory index tree' (duration: 149.227429ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:20:04.574587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.758501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-19T09:20:04.574675Z","caller":"traceutil/trace.go:171","msg":"trace[1817301995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5057; }","duration":"148.901913ms","start":"2026-06-19T09:20:04.425755Z","end":"2026-06-19T09:20:04.574657Z","steps":["trace[1817301995] 'range keys from in-memory index tree' (duration: 148.521305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:20:04.574413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.947644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-19T09:20:04.575387Z","caller":"traceutil/trace.go:171","msg":"trace[1848268232] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:5057; }","duration":"151.025514ms","start":"2026-06-19T09:20:04.424348Z","end":"2026-06-19T09:20:04.575374Z","steps":["trace[1848268232] 'count revisions from in-memory index tree' (duration: 149.768801ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:20:15.720177Z","caller":"traceutil/trace.go:171","msg":"trace[817879048] linearizableReadLoop","detail":"{readStateIndex:5478; appliedIndex:5477; }","duration":"138.27247ms","start":"2026-06-19T09:20:15.581881Z","end":"2026-06-19T09:20:15.720154Z","steps":["trace[817879048] 'read index received' (duration: 33.667988ms)","trace[817879048] 'applied index is now lower than readState.Index' (duration: 104.603602ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:20:15.720282Z","caller":"traceutil/trace.go:171","msg":"trace[1976032984] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"138.8185ms","start":"2026-06-19T09:20:15.581415Z","end":"2026-06-19T09:20:15.720234Z","steps":["trace[1976032984] 'process raft request' (duration: 138.587285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:20:15.720381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.459913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-19T09:20:15.720464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.886696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-6p88h.18ba70f4c1c6785f\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-06-19T09:20:15.720513Z","caller":"traceutil/trace.go:171","msg":"trace[1840993968] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-6p88h.18ba70f4c1c6785f; range_end:; response_count:1; response_revision:5278; }","duration":"105.993258ms","start":"2026-06-19T09:20:15.614505Z","end":"2026-06-19T09:20:15.720498Z","steps":["trace[1840993968] 'agreement among raft nodes before linearized reading' (duration: 105.822025ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:20:15.720509Z","caller":"traceutil/trace.go:171","msg":"trace[1345433479] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:5278; }","duration":"138.625486ms","start":"2026-06-19T09:20:15.581856Z","end":"2026-06-19T09:20:15.720481Z","steps":["trace[1345433479] 'agreement among raft nodes before linearized reading' (duration: 138.405662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:20:24.07783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.893576ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-19T09:20:24.07802Z","caller":"traceutil/trace.go:171","msg":"trace[1229809217] linearizableReadLoop","detail":"{readStateIndex:5662; appliedIndex:5661; }","duration":"141.638165ms","start":"2026-06-19T09:20:23.936357Z","end":"2026-06-19T09:20:24.077995Z","steps":["trace[1229809217] 'read index received' (duration: 68.692µs)","trace[1229809217] 'applied index is now lower than readState.Index' (duration: 141.567263ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T09:20:24.078312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.94031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-19T09:20:24.078356Z","caller":"traceutil/trace.go:171","msg":"trace[408307590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5458; }","duration":"142.035481ms","start":"2026-06-19T09:20:23.936307Z","end":"2026-06-19T09:20:24.078342Z","steps":["trace[408307590] 'agreement among raft nodes before linearized reading' (duration: 141.759106ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:21:35.584153Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3024} {"level":"info","ts":"2026-06-19T09:21:35.676107Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3024,"took":"90.686751ms","hash":4245322995,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":31928320,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-19T09:21:35.676172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4245322995,"revision":3024,"compact-revision":-1} {"level":"info","ts":"2026-06-19T09:22:33.47219Z","caller":"traceutil/trace.go:171","msg":"trace[967856064] transaction","detail":"{read_only:false; response_revision:6498; number_of_response:1; }","duration":"152.717117ms","start":"2026-06-19T09:22:33.319432Z","end":"2026-06-19T09:22:33.472149Z","steps":["trace[967856064] 'process raft request' (duration: 152.410801ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:25:36.085132Z","caller":"traceutil/trace.go:171","msg":"trace[1947884339] transaction","detail":"{read_only:false; response_revision:7803; number_of_response:1; }","duration":"124.843782ms","start":"2026-06-19T09:25:35.95991Z","end":"2026-06-19T09:25:36.084753Z","steps":["trace[1947884339] 'process raft request' (duration: 88.002226ms)","trace[1947884339] 'compare' (duration: 36.744394ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:26:35.591442Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6125} {"level":"info","ts":"2026-06-19T09:26:35.679156Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6125,"took":"86.224871ms","hash":713879630,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":23384064,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-06-19T09:26:35.679244Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":713879630,"revision":6125,"compact-revision":3024} {"level":"info","ts":"2026-06-19T09:26:36.275985Z","caller":"traceutil/trace.go:171","msg":"trace[228648801] transaction","detail":"{read_only:false; response_revision:8322; number_of_response:1; }","duration":"227.74381ms","start":"2026-06-19T09:26:36.048212Z","end":"2026-06-19T09:26:36.275955Z","steps":["trace[228648801] 'process raft request' (duration: 227.585187ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:26:36.552676Z","caller":"traceutil/trace.go:171","msg":"trace[530738003] transaction","detail":"{read_only:false; response_revision:8323; number_of_response:1; }","duration":"331.687088ms","start":"2026-06-19T09:26:36.220966Z","end":"2026-06-19T09:26:36.552653Z","steps":["trace[530738003] 'process raft request' (duration: 242.627783ms)","trace[530738003] 'compare' (duration: 88.884432ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T09:26:36.552797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T09:26:36.220943Z","time spent":"331.795061ms","remote":"127.0.0.1:46730","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-06-19T09:26:36.552803Z","caller":"traceutil/trace.go:171","msg":"trace[1807827349] transaction","detail":"{read_only:false; response_revision:8324; number_of_response:1; }","duration":"164.752463ms","start":"2026-06-19T09:26:36.388022Z","end":"2026-06-19T09:26:36.552775Z","steps":["trace[1807827349] 'process raft request' (duration: 164.595651ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:26:47.481717Z","caller":"traceutil/trace.go:171","msg":"trace[1408012974] linearizableReadLoop","detail":"{readStateIndex:8678; appliedIndex:8677; }","duration":"140.743974ms","start":"2026-06-19T09:26:47.340953Z","end":"2026-06-19T09:26:47.481697Z","steps":["trace[1408012974] 'read index received' (duration: 140.51838ms)","trace[1408012974] 'applied index is now lower than readState.Index' (duration: 224.394µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:26:47.481803Z","caller":"traceutil/trace.go:171","msg":"trace[178275085] transaction","detail":"{read_only:false; response_revision:8383; number_of_response:1; }","duration":"152.430547ms","start":"2026-06-19T09:26:47.329352Z","end":"2026-06-19T09:26:47.481783Z","steps":["trace[178275085] 'process raft request' (duration: 152.181923ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:26:47.481892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.710413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-19T09:26:47.481883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.906547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T09:26:47.481941Z","caller":"traceutil/trace.go:171","msg":"trace[680527753] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8383; }","duration":"140.792435ms","start":"2026-06-19T09:26:47.341136Z","end":"2026-06-19T09:26:47.481929Z","steps":["trace[680527753] 'agreement among raft nodes before linearized reading' (duration: 140.702164ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:26:47.481956Z","caller":"traceutil/trace.go:171","msg":"trace[724632379] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8383; }","duration":"141.015829ms","start":"2026-06-19T09:26:47.340925Z","end":"2026-06-19T09:26:47.481941Z","steps":["trace[724632379] 'agreement among raft nodes before linearized reading' (duration: 140.862907ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:26:47.482084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.592464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T09:26:47.482119Z","caller":"traceutil/trace.go:171","msg":"trace[526114779] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8383; }","duration":"113.668276ms","start":"2026-06-19T09:26:47.368442Z","end":"2026-06-19T09:26:47.48211Z","steps":["trace[526114779] 'agreement among raft nodes before linearized reading' (duration: 113.617015ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:26:47.482332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.762947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T09:26:47.482383Z","caller":"traceutil/trace.go:171","msg":"trace[488107347] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8383; }","duration":"125.816918ms","start":"2026-06-19T09:26:47.356553Z","end":"2026-06-19T09:26:47.48237Z","steps":["trace[488107347] 'agreement among raft nodes before linearized reading' (duration: 125.718797ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:26:47.482428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.528518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T09:26:47.48246Z","caller":"traceutil/trace.go:171","msg":"trace[115003430] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8383; }","duration":"131.588619ms","start":"2026-06-19T09:26:47.350863Z","end":"2026-06-19T09:26:47.482452Z","steps":["trace[115003430] 'agreement among raft nodes before linearized reading' (duration: 131.541858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:26:47.482533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.606169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T09:26:47.482555Z","caller":"traceutil/trace.go:171","msg":"trace[1886599971] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8383; }","duration":"131.64009ms","start":"2026-06-19T09:26:47.35091Z","end":"2026-06-19T09:26:47.48255Z","steps":["trace[1886599971] 'agreement among raft nodes before linearized reading' (duration: 131.605629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:26:47.482606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.337587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T09:26:47.482658Z","caller":"traceutil/trace.go:171","msg":"trace[1294986641] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8383; }","duration":"141.419408ms","start":"2026-06-19T09:26:47.341223Z","end":"2026-06-19T09:26:47.482642Z","steps":["trace[1294986641] 'agreement among raft nodes before linearized reading' (duration: 141.340076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:26:47.482684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.942053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T09:26:47.482751Z","caller":"traceutil/trace.go:171","msg":"trace[1046915475] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8383; }","duration":"113.065615ms","start":"2026-06-19T09:26:47.36967Z","end":"2026-06-19T09:26:47.482736Z","steps":["trace[1046915475] 'agreement among raft nodes before linearized reading' (duration: 112.963733ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:26:47.483027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.527783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T09:26:47.483066Z","caller":"traceutil/trace.go:171","msg":"trace[1932377777] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8383; }","duration":"114.605994ms","start":"2026-06-19T09:26:47.368449Z","end":"2026-06-19T09:26:47.483055Z","steps":["trace[1932377777] 'agreement among raft nodes before linearized reading' (duration: 114.543233ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:26:48.354798Z","caller":"traceutil/trace.go:171","msg":"trace[841449636] transaction","detail":"{read_only:false; response_revision:8384; number_of_response:1; }","duration":"137.296378ms","start":"2026-06-19T09:26:48.217464Z","end":"2026-06-19T09:26:48.35476Z","steps":["trace[841449636] 'process raft request' (duration: 137.055153ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:26:48.77895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.294078ms","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-06-19T09:26:48.779039Z","caller":"traceutil/trace.go:171","msg":"trace[808227956] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8385; }","duration":"325.43017ms","start":"2026-06-19T09:26:48.453589Z","end":"2026-06-19T09:26:48.779019Z","steps":["trace[808227956] 'range keys from in-memory index tree' (duration: 325.123775ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:26:48.77909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T09:26:48.453572Z","time spent":"325.502962ms","remote":"127.0.0.1:46638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-19T09:26:48.779175Z","caller":"traceutil/trace.go:171","msg":"trace[1445620673] transaction","detail":"{read_only:false; response_revision:8386; number_of_response:1; }","duration":"182.895111ms","start":"2026-06-19T09:26:48.596252Z","end":"2026-06-19T09:26:48.779148Z","steps":["trace[1445620673] 'process raft request' (duration: 130.706883ms)","trace[1445620673] 'compare' (duration: 51.643118ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:26:48.850954Z","caller":"traceutil/trace.go:171","msg":"trace[91339843] linearizableReadLoop","detail":"{readStateIndex:8682; appliedIndex:8680; }","duration":"159.786139ms","start":"2026-06-19T09:26:48.691144Z","end":"2026-06-19T09:26:48.85093Z","steps":["trace[91339843] 'read index received' (duration: 35.896617ms)","trace[91339843] 'applied index is now lower than readState.Index' (duration: 123.888522ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:26:48.851094Z","caller":"traceutil/trace.go:171","msg":"trace[30450354] transaction","detail":"{read_only:false; response_revision:8387; number_of_response:1; }","duration":"249.091018ms","start":"2026-06-19T09:26:48.601984Z","end":"2026-06-19T09:26:48.851075Z","steps":["trace[30450354] 'process raft request' (duration: 248.796443ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:26:48.851326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.186647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-19T09:26:48.851372Z","caller":"traceutil/trace.go:171","msg":"trace[681706114] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:8387; }","duration":"160.280848ms","start":"2026-06-19T09:26:48.691079Z","end":"2026-06-19T09:26:48.85136Z","steps":["trace[681706114] 'agreement among raft nodes before linearized reading' (duration: 160.136155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:26:48.851542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.719673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.250\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-19T09:26:48.851623Z","caller":"traceutil/trace.go:171","msg":"trace[908234844] range","detail":"{range_begin:/registry/masterleases/199.19.213.250; range_end:; response_count:1; response_revision:8387; }","duration":"118.834534ms","start":"2026-06-19T09:26:48.732771Z","end":"2026-06-19T09:26:48.851606Z","steps":["trace[908234844] 'agreement among raft nodes before linearized reading' (duration: 118.60795ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:26:49.412973Z","caller":"traceutil/trace.go:171","msg":"trace[1837691109] transaction","detail":"{read_only:false; response_revision:8393; number_of_response:1; }","duration":"238.658158ms","start":"2026-06-19T09:26:49.174277Z","end":"2026-06-19T09:26:49.412935Z","steps":["trace[1837691109] 'process raft request' (duration: 238.414794ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:27:17.529738Z","caller":"traceutil/trace.go:171","msg":"trace[2071648520] linearizableReadLoop","detail":"{readStateIndex:8901; appliedIndex:8900; }","duration":"156.439975ms","start":"2026-06-19T09:27:17.373262Z","end":"2026-06-19T09:27:17.529702Z","steps":["trace[2071648520] 'read index received' (duration: 122.765959ms)","trace[2071648520] 'applied index is now lower than readState.Index' (duration: 33.672856ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:27:17.529883Z","caller":"traceutil/trace.go:171","msg":"trace[276697715] transaction","detail":"{read_only:false; response_revision:8598; number_of_response:1; }","duration":"203.464206ms","start":"2026-06-19T09:27:17.326381Z","end":"2026-06-19T09:27:17.529845Z","steps":["trace[276697715] 'process raft request' (duration: 169.668138ms)","trace[276697715] 'compare' (duration: 33.535512ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T09:27:17.529914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.616928ms","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":"info","ts":"2026-06-19T09:27:17.530018Z","caller":"traceutil/trace.go:171","msg":"trace[2088149522] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8598; }","duration":"156.794942ms","start":"2026-06-19T09:27:17.373207Z","end":"2026-06-19T09:27:17.530002Z","steps":["trace[2088149522] 'agreement among raft nodes before linearized reading' (duration: 156.633999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:27:21.241605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.312938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-19T09:27:21.241709Z","caller":"traceutil/trace.go:171","msg":"trace[417992256] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:8616; }","duration":"103.487341ms","start":"2026-06-19T09:27:21.1382Z","end":"2026-06-19T09:27:21.241688Z","steps":["trace[417992256] 'count revisions from in-memory index tree' (duration: 103.218156ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:27:21.892868Z","caller":"traceutil/trace.go:171","msg":"trace[1916963502] linearizableReadLoop","detail":"{readStateIndex:8926; appliedIndex:8925; }","duration":"168.877743ms","start":"2026-06-19T09:27:21.723969Z","end":"2026-06-19T09:27:21.892847Z","steps":["trace[1916963502] 'read index received' (duration: 168.667839ms)","trace[1916963502] 'applied index is now lower than readState.Index' (duration: 209.414µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:27:21.892896Z","caller":"traceutil/trace.go:171","msg":"trace[656227019] transaction","detail":"{read_only:false; response_revision:8622; number_of_response:1; }","duration":"169.796501ms","start":"2026-06-19T09:27:21.723081Z","end":"2026-06-19T09:27:21.892877Z","steps":["trace[656227019] 'process raft request' (duration: 169.637488ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:27:21.892973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.986275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-19T09:27:21.892996Z","caller":"traceutil/trace.go:171","msg":"trace[1908805693] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:8622; }","duration":"169.044646ms","start":"2026-06-19T09:27:21.723945Z","end":"2026-06-19T09:27:21.89299Z","steps":["trace[1908805693] 'agreement among raft nodes before linearized reading' (duration: 168.971325ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:27:30.131812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.392986ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T09:27:30.132044Z","caller":"traceutil/trace.go:171","msg":"trace[1966869830] transaction","detail":"{read_only:false; response_revision:8706; number_of_response:1; }","duration":"214.68491ms","start":"2026-06-19T09:27:29.917325Z","end":"2026-06-19T09:27:30.13201Z","steps":["trace[1966869830] 'process raft request' (duration: 83.790754ms)","trace[1966869830] 'compare' (duration: 130.083421ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:27:30.132076Z","caller":"traceutil/trace.go:171","msg":"trace[1571257802] transaction","detail":"{read_only:false; response_revision:8707; number_of_response:1; }","duration":"201.777123ms","start":"2026-06-19T09:27:29.930275Z","end":"2026-06-19T09:27:30.132053Z","steps":["trace[1571257802] 'process raft request' (duration: 201.665041ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:27:30.13228Z","caller":"traceutil/trace.go:171","msg":"trace[1184044614] linearizableReadLoop","detail":"{readStateIndex:9015; appliedIndex:9014; }","duration":"202.335733ms","start":"2026-06-19T09:27:29.929784Z","end":"2026-06-19T09:27:30.13212Z","steps":["trace[1184044614] 'read index received' (duration: 71.435137ms)","trace[1184044614] 'applied index is now lower than readState.Index' (duration: 130.890376ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T09:27:30.132547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.752652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-bntdl\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-06-19T09:27:30.1326Z","caller":"traceutil/trace.go:171","msg":"trace[2144280959] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-bntdl; range_end:; response_count:1; response_revision:8707; }","duration":"202.816203ms","start":"2026-06-19T09:27:29.92977Z","end":"2026-06-19T09:27:30.132586Z","steps":["trace[2144280959] 'agreement among raft nodes before linearized reading' (duration: 202.617049ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:27:30.309811Z","caller":"traceutil/trace.go:171","msg":"trace[1135738336] linearizableReadLoop","detail":"{readStateIndex:9017; appliedIndex:9016; }","duration":"153.890707ms","start":"2026-06-19T09:27:30.155894Z","end":"2026-06-19T09:27:30.309785Z","steps":["trace[1135738336] 'read index received' (duration: 149.207087ms)","trace[1135738336] 'applied index is now lower than readState.Index' (duration: 4.6823ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T09:27:30.309976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.06557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T09:27:30.309979Z","caller":"traceutil/trace.go:171","msg":"trace[1065834103] transaction","detail":"{read_only:false; response_revision:8708; number_of_response:1; }","duration":"168.758641ms","start":"2026-06-19T09:27:30.141174Z","end":"2026-06-19T09:27:30.309932Z","steps":["trace[1065834103] 'process raft request' (duration: 163.898628ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:27:30.310022Z","caller":"traceutil/trace.go:171","msg":"trace[1740196637] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:8708; }","duration":"154.178832ms","start":"2026-06-19T09:27:30.155831Z","end":"2026-06-19T09:27:30.31001Z","steps":["trace[1740196637] 'agreement among raft nodes before linearized reading' (duration: 154.04944ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:27:30.970654Z","caller":"traceutil/trace.go:171","msg":"trace[114423332] transaction","detail":"{read_only:false; response_revision:8710; number_of_response:1; }","duration":"152.816176ms","start":"2026-06-19T09:27:30.817806Z","end":"2026-06-19T09:27:30.970623Z","steps":["trace[114423332] 'process raft request' (duration: 152.638922ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:27:31.10107Z","caller":"traceutil/trace.go:171","msg":"trace[2017265331] transaction","detail":"{read_only:false; response_revision:8712; number_of_response:1; }","duration":"163.972599ms","start":"2026-06-19T09:27:30.937059Z","end":"2026-06-19T09:27:31.101031Z","steps":["trace[2017265331] 'process raft request' (duration: 163.841316ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:27:31.1011Z","caller":"traceutil/trace.go:171","msg":"trace[967731155] transaction","detail":"{read_only:false; response_revision:8711; number_of_response:1; }","duration":"258.154033ms","start":"2026-06-19T09:27:30.842914Z","end":"2026-06-19T09:27:31.101068Z","steps":["trace[967731155] 'process raft request' (duration: 181.569257ms)","trace[967731155] 'compare' (duration: 76.247609ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:27:47.014989Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023d5.wal"} {"level":"warn","ts":"2026-06-19T09:28:49.071775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.462736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-19T09:28:49.071881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.14732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-19T09:28:49.071889Z","caller":"traceutil/trace.go:171","msg":"trace[1956074890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9464; }","duration":"185.615148ms","start":"2026-06-19T09:28:48.886241Z","end":"2026-06-19T09:28:49.071856Z","steps":["trace[1956074890] 'range keys from in-memory index tree' (duration: 185.385005ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:28:49.071944Z","caller":"traceutil/trace.go:171","msg":"trace[846199129] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9464; }","duration":"112.247553ms","start":"2026-06-19T09:28:48.95968Z","end":"2026-06-19T09:28:49.071927Z","steps":["trace[846199129] 'range keys from in-memory index tree' (duration: 111.986688ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:29:15.930689Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1918dd9317ae83a2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-19T09:29:15.970106Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-19T09:29:15.970217Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-19T09:31:35.597978Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8321} {"level":"info","ts":"2026-06-19T09:31:35.678001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8321,"took":"78.159619ms","hash":1134095120,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":25210880,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-06-19T09:31:35.678058Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1134095120,"revision":8321,"compact-revision":6125} {"level":"info","ts":"2026-06-19T09:34:06.44358Z","caller":"traceutil/trace.go:171","msg":"trace[1347314787] transaction","detail":"{read_only:false; response_revision:11780; number_of_response:1; }","duration":"111.544998ms","start":"2026-06-19T09:34:06.331993Z","end":"2026-06-19T09:34:06.443538Z","steps":["trace[1347314787] 'process raft request' (duration: 111.036849ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:34:27.30273Z","caller":"traceutil/trace.go:171","msg":"trace[827481149] linearizableReadLoop","detail":"{readStateIndex:12305; appliedIndex:12304; }","duration":"270.379253ms","start":"2026-06-19T09:34:27.032318Z","end":"2026-06-19T09:34:27.302697Z","steps":["trace[827481149] 'read index received' (duration: 270.20736ms)","trace[827481149] 'applied index is now lower than readState.Index' (duration: 170.503µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:34:27.302869Z","caller":"traceutil/trace.go:171","msg":"trace[1972503592] transaction","detail":"{read_only:false; response_revision:11897; number_of_response:1; }","duration":"274.153595ms","start":"2026-06-19T09:34:27.028605Z","end":"2026-06-19T09:34:27.302759Z","steps":["trace[1972503592] 'process raft request' (duration: 273.923471ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:34:27.303432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.783031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T09:34:27.303695Z","caller":"traceutil/trace.go:171","msg":"trace[99262275] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11897; }","duration":"271.463984ms","start":"2026-06-19T09:34:27.0322Z","end":"2026-06-19T09:34:27.303664Z","steps":["trace[99262275] 'agreement among raft nodes before linearized reading' (duration: 270.715659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:34:27.331616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.409601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-19T09:34:27.331648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.396101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T09:34:27.331713Z","caller":"traceutil/trace.go:171","msg":"trace[873348919] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11898; }","duration":"286.525224ms","start":"2026-06-19T09:34:27.045168Z","end":"2026-06-19T09:34:27.331693Z","steps":["trace[873348919] 'agreement among raft nodes before linearized reading' (duration: 286.34968ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:34:27.331713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.791059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-06-19T09:34:27.331739Z","caller":"traceutil/trace.go:171","msg":"trace[327803618] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11898; }","duration":"286.524973ms","start":"2026-06-19T09:34:27.045191Z","end":"2026-06-19T09:34:27.331716Z","steps":["trace[327803618] 'agreement among raft nodes before linearized reading' (duration: 286.336069ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:34:27.331781Z","caller":"traceutil/trace.go:171","msg":"trace[157168855] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11898; }","duration":"273.930481ms","start":"2026-06-19T09:34:27.057835Z","end":"2026-06-19T09:34:27.331766Z","steps":["trace[157168855] 'agreement among raft nodes before linearized reading' (duration: 273.735747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:34:27.331923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.842329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T09:34:27.331961Z","caller":"traceutil/trace.go:171","msg":"trace[1908346418] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11898; }","duration":"285.879911ms","start":"2026-06-19T09:34:27.046071Z","end":"2026-06-19T09:34:27.331951Z","steps":["trace[1908346418] 'agreement among raft nodes before linearized reading' (duration: 285.82349ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:34:27.331982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.621114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T09:34:27.332015Z","caller":"traceutil/trace.go:171","msg":"trace[76453873] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11898; }","duration":"299.705496ms","start":"2026-06-19T09:34:27.032299Z","end":"2026-06-19T09:34:27.332005Z","steps":["trace[76453873] 'agreement among raft nodes before linearized reading' (duration: 299.653775ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:34:27.332104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.656034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-06-19T09:34:27.332156Z","caller":"traceutil/trace.go:171","msg":"trace[96127305] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11898; }","duration":"299.745186ms","start":"2026-06-19T09:34:27.032401Z","end":"2026-06-19T09:34:27.332146Z","steps":["trace[96127305] 'agreement among raft nodes before linearized reading' (duration: 299.518002ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:34:27.332176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.656665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T09:34:27.332209Z","caller":"traceutil/trace.go:171","msg":"trace[1077941140] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11898; }","duration":"299.710115ms","start":"2026-06-19T09:34:27.032492Z","end":"2026-06-19T09:34:27.332202Z","steps":["trace[1077941140] 'agreement among raft nodes before linearized reading' (duration: 299.668145ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:34:27.332115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.156586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-19T09:34:27.332284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.293138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-19T09:34:27.332297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.564635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-06-19T09:34:27.332383Z","caller":"traceutil/trace.go:171","msg":"trace[1241952879] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11898; }","duration":"287.41139ms","start":"2026-06-19T09:34:27.044946Z","end":"2026-06-19T09:34:27.332358Z","steps":["trace[1241952879] 'agreement among raft nodes before linearized reading' (duration: 287.199596ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:34:27.332413Z","caller":"traceutil/trace.go:171","msg":"trace[1023787556] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11898; }","duration":"286.707567ms","start":"2026-06-19T09:34:27.045683Z","end":"2026-06-19T09:34:27.33239Z","steps":["trace[1023787556] 'agreement among raft nodes before linearized reading' (duration: 286.379611ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:34:27.332353Z","caller":"traceutil/trace.go:171","msg":"trace[2082453685] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11898; }","duration":"299.4113ms","start":"2026-06-19T09:34:27.032934Z","end":"2026-06-19T09:34:27.332345Z","steps":["trace[2082453685] 'agreement among raft nodes before linearized reading' (duration: 299.164196ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:34:27.332142Z","caller":"traceutil/trace.go:171","msg":"trace[804881051] transaction","detail":"{read_only:false; response_revision:11898; number_of_response:1; }","duration":"133.085562ms","start":"2026-06-19T09:34:27.199032Z","end":"2026-06-19T09:34:27.332118Z","steps":["trace[804881051] 'process raft request' (duration: 113.110789ms)","trace[804881051] 'compare' (duration: 19.161297ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:36:35.604791Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10541} {"level":"info","ts":"2026-06-19T09:36:35.682794Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10541,"took":"75.729062ms","hash":1806761054,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":26267648,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-19T09:36:35.682881Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1806761054,"revision":10541,"compact-revision":8321} {"level":"info","ts":"2026-06-19T09:40:58.29202Z","caller":"traceutil/trace.go:171","msg":"trace[752362028] transaction","detail":"{read_only:false; response_revision:14506; number_of_response:1; }","duration":"169.375114ms","start":"2026-06-19T09:40:58.122612Z","end":"2026-06-19T09:40:58.291987Z","steps":["trace[752362028] 'process raft request' (duration: 155.768013ms)","trace[752362028] 'compare' (duration: 12.816706ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:41:14.612253Z","caller":"traceutil/trace.go:171","msg":"trace[952890625] linearizableReadLoop","detail":"{readStateIndex:15110; appliedIndex:15109; }","duration":"107.585628ms","start":"2026-06-19T09:41:14.504618Z","end":"2026-06-19T09:41:14.612203Z","steps":["trace[952890625] 'read index received' (duration: 107.368684ms)","trace[952890625] 'applied index is now lower than readState.Index' (duration: 216.044µs)"],"step_count":2} {"level":"warn","ts":"2026-06-19T09:41:14.612392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.758391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T09:41:14.612374Z","caller":"traceutil/trace.go:171","msg":"trace[312538673] transaction","detail":"{read_only:false; response_revision:14605; number_of_response:1; }","duration":"155.30559ms","start":"2026-06-19T09:41:14.457024Z","end":"2026-06-19T09:41:14.612329Z","steps":["trace[312538673] 'process raft request' (duration: 155.039135ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:41:14.612436Z","caller":"traceutil/trace.go:171","msg":"trace[316786880] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:14605; }","duration":"107.852813ms","start":"2026-06-19T09:41:14.504571Z","end":"2026-06-19T09:41:14.612423Z","steps":["trace[316786880] 'agreement among raft nodes before linearized reading' (duration: 107.764751ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:41:35.614251Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12766} {"level":"info","ts":"2026-06-19T09:41:35.69114Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12766,"took":"74.031887ms","hash":2629989066,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":28844032,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-19T09:41:35.691193Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2629989066,"revision":12766,"compact-revision":10541} {"level":"info","ts":"2026-06-19T09:41:36.592728Z","caller":"traceutil/trace.go:171","msg":"trace[1486421426] linearizableReadLoop","detail":"{readStateIndex:15431; appliedIndex:15430; }","duration":"353.804368ms","start":"2026-06-19T09:41:36.238894Z","end":"2026-06-19T09:41:36.592699Z","steps":["trace[1486421426] 'read index received' (duration: 353.676006ms)","trace[1486421426] 'applied index is now lower than readState.Index' (duration: 127.252µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T09:41:36.592856Z","caller":"traceutil/trace.go:171","msg":"trace[1882704653] transaction","detail":"{read_only:false; response_revision:14912; number_of_response:1; }","duration":"356.066173ms","start":"2026-06-19T09:41:36.23676Z","end":"2026-06-19T09:41:36.592826Z","steps":["trace[1882704653] 'process raft request' (duration: 355.771196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:41:36.592912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.176202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d948dc6c-da03-412b-9f2b-3988b0305a4e\" ","response":"range_response_count:1 size:3757"} {"level":"info","ts":"2026-06-19T09:41:36.592976Z","caller":"traceutil/trace.go:171","msg":"trace[1900332268] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d948dc6c-da03-412b-9f2b-3988b0305a4e; range_end:; response_count:1; response_revision:14912; }","duration":"337.305634ms","start":"2026-06-19T09:41:36.255657Z","end":"2026-06-19T09:41:36.592962Z","steps":["trace[1900332268] 'agreement among raft nodes before linearized reading' (duration: 337.194542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:41:36.592965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.048454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18966"} {"level":"warn","ts":"2026-06-19T09:41:36.593013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T09:41:36.255623Z","time spent":"337.381185ms","remote":"127.0.0.1:46638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3779,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d948dc6c-da03-412b-9f2b-3988b0305a4e\" "} {"level":"info","ts":"2026-06-19T09:41:36.593056Z","caller":"traceutil/trace.go:171","msg":"trace[476188326] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:14912; }","duration":"354.174877ms","start":"2026-06-19T09:41:36.238861Z","end":"2026-06-19T09:41:36.593036Z","steps":["trace[476188326] 'agreement among raft nodes before linearized reading' (duration: 353.928971ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:41:36.593111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T09:41:36.238844Z","time spent":"354.255928ms","remote":"127.0.0.1:46632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18988,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-19T09:41:36.593002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T09:41:36.236734Z","time spent":"356.193926ms","remote":"127.0.0.1:46638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3742,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T09:43:02.268949Z","caller":"traceutil/trace.go:171","msg":"trace[2128456501] transaction","detail":"{read_only:false; response_revision:15718; number_of_response:1; }","duration":"124.550652ms","start":"2026-06-19T09:43:02.144376Z","end":"2026-06-19T09:43:02.268927Z","steps":["trace[2128456501] 'process raft request' (duration: 124.391189ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:43:02.53363Z","caller":"traceutil/trace.go:171","msg":"trace[582118763] transaction","detail":"{read_only:false; response_revision:15719; number_of_response:1; }","duration":"388.633975ms","start":"2026-06-19T09:43:02.144958Z","end":"2026-06-19T09:43:02.533592Z","steps":["trace[582118763] 'process raft request' (duration: 378.277847ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:43:02.533733Z","caller":"traceutil/trace.go:171","msg":"trace[503860839] linearizableReadLoop","detail":"{readStateIndex:16260; appliedIndex:16258; }","duration":"388.444962ms","start":"2026-06-19T09:43:02.14525Z","end":"2026-06-19T09:43:02.533695Z","steps":["trace[503860839] 'read index received' (duration: 123.603205ms)","trace[503860839] 'applied index is now lower than readState.Index' (duration: 264.839797ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T09:43:02.533854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T09:43:02.144943Z","time spent":"388.792429ms","remote":"127.0.0.1:46730","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-06-19T09:43:02.53383Z","caller":"traceutil/trace.go:171","msg":"trace[2012864252] transaction","detail":"{read_only:false; response_revision:15720; number_of_response:1; }","duration":"387.707068ms","start":"2026-06-19T09:43:02.146095Z","end":"2026-06-19T09:43:02.533802Z","steps":["trace[2012864252] 'process raft request' (duration: 387.410593ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:43:02.533999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T09:43:02.146087Z","time spent":"387.82986ms","remote":"127.0.0.1:46730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T09:43:02.534826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.501282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737332"} {"level":"info","ts":"2026-06-19T09:43:02.534895Z","caller":"traceutil/trace.go:171","msg":"trace[1005997100] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15720; }","duration":"389.635775ms","start":"2026-06-19T09:43:02.145247Z","end":"2026-06-19T09:43:02.534883Z","steps":["trace[1005997100] 'agreement among raft nodes before linearized reading' (duration: 388.641096ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:43:02.534935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T09:43:02.145229Z","time spent":"389.696425ms","remote":"127.0.0.1:46638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":76,"response size":737354,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-06-19T09:43:02.541553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.556032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-19T09:43:02.541593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.85138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-zbglh\" ","response":"range_response_count:1 size:22100"} {"level":"info","ts":"2026-06-19T09:43:02.541627Z","caller":"traceutil/trace.go:171","msg":"trace[1410717493] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-zbglh; range_end:; response_count:1; response_revision:15721; }","duration":"156.937112ms","start":"2026-06-19T09:43:02.384681Z","end":"2026-06-19T09:43:02.541618Z","steps":["trace[1410717493] 'agreement among raft nodes before linearized reading' (duration: 156.82235ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:43:02.541626Z","caller":"traceutil/trace.go:171","msg":"trace[304745587] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15721; }","duration":"275.675305ms","start":"2026-06-19T09:43:02.265932Z","end":"2026-06-19T09:43:02.541607Z","steps":["trace[304745587] 'agreement among raft nodes before linearized reading' (duration: 275.46945ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:43:02.542021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.809969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-zbglh\" ","response":"range_response_count:1 size:22100"} {"level":"info","ts":"2026-06-19T09:43:02.542122Z","caller":"traceutil/trace.go:171","msg":"trace[254894744] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-zbglh; range_end:; response_count:1; response_revision:15721; }","duration":"215.965862ms","start":"2026-06-19T09:43:02.326131Z","end":"2026-06-19T09:43:02.542097Z","steps":["trace[254894744] 'agreement among raft nodes before linearized reading' (duration: 215.415922ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:46:35.621747Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14900} {"level":"info","ts":"2026-06-19T09:46:35.714355Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14900,"took":"89.738877ms","hash":820291553,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":30523392,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-19T09:46:35.714447Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":820291553,"revision":14900,"compact-revision":12766} {"level":"warn","ts":"2026-06-19T09:48:07.964629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.677564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-19T09:48:07.964719Z","caller":"traceutil/trace.go:171","msg":"trace[461122190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17972; }","duration":"124.799657ms","start":"2026-06-19T09:48:07.839901Z","end":"2026-06-19T09:48:07.9647Z","steps":["trace[461122190] 'range keys from in-memory index tree' (duration: 124.522581ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T09:48:07.965367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.830899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:944719"} {"level":"info","ts":"2026-06-19T09:48:07.965556Z","caller":"traceutil/trace.go:171","msg":"trace[1417961385] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:17972; }","duration":"123.040013ms","start":"2026-06-19T09:48:07.842499Z","end":"2026-06-19T09:48:07.965539Z","steps":["trace[1417961385] 'range keys from in-memory index tree' (duration: 121.960823ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T09:51:35.629252Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17382} {"level":"info","ts":"2026-06-19T09:51:35.717762Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17382,"took":"85.326593ms","hash":2879464853,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":28983296,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-19T09:51:35.717834Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2879464853,"revision":17382,"compact-revision":14900} {"level":"info","ts":"2026-06-19T09:52:08.815358Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1918dd9317ae83a2","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-19T09:52:08.82126Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-19T09:52:08.821354Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-19T09:56:35.634999Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19156} {"level":"info","ts":"2026-06-19T09:56:35.719624Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19156,"took":"82.543869ms","hash":1768749560,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":23883776,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-19T09:56:35.719701Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1768749560,"revision":19156,"compact-revision":17382}