{"level":"warn","ts":"2026-05-09T01:37:43.123065Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T01:37:43.123203Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.19:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.19:2380","--initial-cluster=instance=https://199.204.45.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.19:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-09T01:37:43.123316Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T01:37:43.123338Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-05-09T01:37:43.123364Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T01:37:43.12424Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"]} {"level":"info","ts":"2026-05-09T01:37:43.124344Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.19:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-09T01:37:43.12889Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.192887ms"} {"level":"info","ts":"2026-05-09T01:37:43.140979Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-05-09T01:37:43.141089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T01:37:43.141128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-05-09T01:37:43.141148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a847c2e9c7010db6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T01:37:43.141162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-05-09T01:37:43.141212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-05-09T01:37:43.146918Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T01:37:43.148835Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T01:37:43.150291Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-09T01:37:43.153691Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a847c2e9c7010db6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T01:37:43.153921Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a847c2e9c7010db6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T01:37:43.154001Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T01:37:43.154178Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T01:37:43.154202Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T01:37:43.154675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-05-09T01:37:43.155073Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","added-peer-id":"a847c2e9c7010db6","added-peer-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-05-09T01:37:43.158386Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T01:37:43.158483Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-05-09T01:37:43.158552Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-05-09T01:37:43.158632Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a847c2e9c7010db6","initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T01:37:43.158683Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T01:37:43.541644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T01:37:43.541726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T01:37:43.541755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-05-09T01:37:43.541777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-05-09T01:37:43.541788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-05-09T01:37:43.541806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-05-09T01:37:43.541821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-05-09T01:37:43.544675Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a847c2e9c7010db6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.19:2379]}","request-path":"/0/members/a847c2e9c7010db6/attributes","cluster-id":"2b11a68e27507b05","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T01:37:43.544681Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T01:37:43.54468Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T01:37:43.544956Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T01:37:43.545389Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T01:37:43.545454Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T01:37:43.546563Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T01:37:43.546701Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T01:37:43.546754Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T01:37:43.547088Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T01:37:43.547304Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} {"level":"info","ts":"2026-05-09T01:37:58.817165Z","caller":"traceutil/trace.go:171","msg":"trace[2141602829] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:379; }","duration":"234.000134ms","start":"2026-05-09T01:37:58.58312Z","end":"2026-05-09T01:37:58.817121Z","steps":["trace[2141602829] 'read index received' (duration: 233.779618ms)","trace[2141602829] 'applied index is now lower than readState.Index' (duration: 219.246µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:37:58.817264Z","caller":"traceutil/trace.go:171","msg":"trace[97745545] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"331.13745ms","start":"2026-05-09T01:37:58.486084Z","end":"2026-05-09T01:37:58.817222Z","steps":["trace[97745545] 'process raft request' (duration: 330.79257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:58.817529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.332954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4372"} {"level":"info","ts":"2026-05-09T01:37:58.817735Z","caller":"traceutil/trace.go:171","msg":"trace[218714663] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:367; }","duration":"234.625751ms","start":"2026-05-09T01:37:58.58308Z","end":"2026-05-09T01:37:58.817706Z","steps":["trace[218714663] 'agreement among raft nodes before linearized reading' (duration: 234.238901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:58.818031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:58.486054Z","time spent":"331.313334ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T01:38:15.079784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.536733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T01:38:15.080176Z","caller":"traceutil/trace.go:171","msg":"trace[1624860393] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"250.898748ms","start":"2026-05-09T01:38:14.829241Z","end":"2026-05-09T01:38:15.080139Z","steps":["trace[1624860393] 'process raft request' (duration: 46.670346ms)","trace[1624860393] 'compare' (duration: 203.156193ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:38:15.080312Z","caller":"traceutil/trace.go:171","msg":"trace[1753521922] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"182.078032ms","start":"2026-05-09T01:38:14.898212Z","end":"2026-05-09T01:38:15.08029Z","steps":["trace[1753521922] 'process raft request' (duration: 181.973959ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:38:15.080488Z","caller":"traceutil/trace.go:171","msg":"trace[523780580] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"239.58477ms","start":"2026-05-09T01:38:14.840891Z","end":"2026-05-09T01:38:15.080476Z","steps":["trace[523780580] 'process raft request' (duration: 239.059176ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:38:15.080583Z","caller":"traceutil/trace.go:171","msg":"trace[2140930488] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"239.56532ms","start":"2026-05-09T01:38:14.841011Z","end":"2026-05-09T01:38:15.080576Z","steps":["trace[2140930488] 'process raft request' (duration: 239.030705ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:38:15.087024Z","caller":"traceutil/trace.go:171","msg":"trace[456419055] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"130.929581ms","start":"2026-05-09T01:38:14.956059Z","end":"2026-05-09T01:38:15.086989Z","steps":["trace[456419055] 'process raft request' (duration: 130.760897ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:38:21.334562Z","caller":"traceutil/trace.go:171","msg":"trace[525854107] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"139.485402ms","start":"2026-05-09T01:38:21.195055Z","end":"2026-05-09T01:38:21.33454Z","steps":["trace[525854107] 'read index received' (duration: 134.953765ms)","trace[525854107] 'applied index is now lower than readState.Index' (duration: 4.530826ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:38:21.334676Z","caller":"traceutil/trace.go:171","msg":"trace[610534980] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"142.529446ms","start":"2026-05-09T01:38:21.192139Z","end":"2026-05-09T01:38:21.334668Z","steps":["trace[610534980] 'process raft request' (duration: 137.821844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:38:21.334918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.821341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:38:21.335026Z","caller":"traceutil/trace.go:171","msg":"trace[1686797981] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:571; }","duration":"139.959604ms","start":"2026-05-09T01:38:21.195038Z","end":"2026-05-09T01:38:21.334998Z","steps":["trace[1686797981] 'agreement among raft nodes before linearized reading' (duration: 139.764159ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:38:21.719949Z","caller":"traceutil/trace.go:171","msg":"trace[215264379] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"187.896136ms","start":"2026-05-09T01:38:21.532017Z","end":"2026-05-09T01:38:21.719913Z","steps":["trace[215264379] 'process raft request' (duration: 187.715191ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:38:21.887489Z","caller":"traceutil/trace.go:171","msg":"trace[1092489870] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"347.482689ms","start":"2026-05-09T01:38:21.53995Z","end":"2026-05-09T01:38:21.887432Z","steps":["trace[1092489870] 'process raft request' (duration: 347.054387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:38:21.88784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:38:21.539919Z","time spent":"347.674824ms","remote":"127.0.0.1:59184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:38:22.016432Z","caller":"traceutil/trace.go:171","msg":"trace[840887297] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"275.563627ms","start":"2026-05-09T01:38:21.74083Z","end":"2026-05-09T01:38:22.016394Z","steps":["trace[840887297] 'process raft request' (duration: 274.881638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:38:22.378524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.01021ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T01:38:22.378672Z","caller":"traceutil/trace.go:171","msg":"trace[1418975756] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"126.901609ms","start":"2026-05-09T01:38:22.251749Z","end":"2026-05-09T01:38:22.37865Z","steps":["trace[1418975756] 'read index received' (duration: 81.962µs)","trace[1418975756] 'applied index is now lower than readState.Index' (duration: 126.818267ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:38:22.378887Z","caller":"traceutil/trace.go:171","msg":"trace[1352179795] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"306.313237ms","start":"2026-05-09T01:38:22.072492Z","end":"2026-05-09T01:38:22.378806Z","steps":["trace[1352179795] 'process raft request' (duration: 137.921808ms)","trace[1352179795] 'compare' (duration: 167.809283ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:38:22.378977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.225919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:38:22.379017Z","caller":"traceutil/trace.go:171","msg":"trace[174033091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"127.322341ms","start":"2026-05-09T01:38:22.251683Z","end":"2026-05-09T01:38:22.379005Z","steps":["trace[174033091] 'agreement among raft nodes before linearized reading' (duration: 127.024833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:38:22.379186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:38:22.072464Z","time spent":"306.533684ms","remote":"127.0.0.1:59314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T01:38:23.767129Z","caller":"traceutil/trace.go:171","msg":"trace[1939630285] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"109.03174ms","start":"2026-05-09T01:38:23.658062Z","end":"2026-05-09T01:38:23.767094Z","steps":["trace[1939630285] 'process raft request' (duration: 79.40458ms)","trace[1939630285] 'compare' (duration: 29.060884ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:40:01.20378Z","caller":"traceutil/trace.go:171","msg":"trace[1412784945] linearizableReadLoop","detail":"{readStateIndex:1897; appliedIndex:1896; }","duration":"120.009912ms","start":"2026-05-09T01:40:01.083735Z","end":"2026-05-09T01:40:01.203745Z","steps":["trace[1412784945] 'read index received' (duration: 119.752245ms)","trace[1412784945] 'applied index is now lower than readState.Index' (duration: 256.207µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:40:01.203898Z","caller":"traceutil/trace.go:171","msg":"trace[1408136090] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"216.984398ms","start":"2026-05-09T01:40:00.986871Z","end":"2026-05-09T01:40:01.203855Z","steps":["trace[1408136090] 'process raft request' (duration: 216.625027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:40:01.204154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.385163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-836dbf8d-c548-4db5-bdeb-75f3825bb1fe\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-09T01:40:01.204206Z","caller":"traceutil/trace.go:171","msg":"trace[1353735655] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-836dbf8d-c548-4db5-bdeb-75f3825bb1fe; range_end:; response_count:1; response_revision:1830; }","duration":"120.497486ms","start":"2026-05-09T01:40:01.083693Z","end":"2026-05-09T01:40:01.20419Z","steps":["trace[1353735655] 'agreement among raft nodes before linearized reading' (duration: 120.196638ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:40:01.339965Z","caller":"traceutil/trace.go:171","msg":"trace[1474668737] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"128.698474ms","start":"2026-05-09T01:40:01.211224Z","end":"2026-05-09T01:40:01.339923Z","steps":["trace[1474668737] 'process raft request' (duration: 99.323421ms)","trace[1474668737] 'compare' (duration: 29.131236ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:40:15.62241Z","caller":"traceutil/trace.go:171","msg":"trace[44738466] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"117.988587ms","start":"2026-05-09T01:40:15.504394Z","end":"2026-05-09T01:40:15.622382Z","steps":["trace[44738466] 'process raft request' (duration: 117.814162ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:40:51.606056Z","caller":"traceutil/trace.go:171","msg":"trace[2141309267] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"144.02853ms","start":"2026-05-09T01:40:51.462005Z","end":"2026-05-09T01:40:51.606033Z","steps":["trace[2141309267] 'process raft request' (duration: 143.877125ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:46:16.621855Z","caller":"traceutil/trace.go:171","msg":"trace[1329405476] linearizableReadLoop","detail":"{readStateIndex:5088; appliedIndex:5086; }","duration":"130.202791ms","start":"2026-05-09T01:46:16.491627Z","end":"2026-05-09T01:46:16.62183Z","steps":["trace[1329405476] 'read index received' (duration: 119.632692ms)","trace[1329405476] 'applied index is now lower than readState.Index' (duration: 10.569079ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:46:16.621881Z","caller":"traceutil/trace.go:171","msg":"trace[2076732697] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"140.583134ms","start":"2026-05-09T01:46:16.481264Z","end":"2026-05-09T01:46:16.621848Z","steps":["trace[2076732697] 'process raft request' (duration: 140.489721ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:46:16.621948Z","caller":"traceutil/trace.go:171","msg":"trace[270153303] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"142.733543ms","start":"2026-05-09T01:46:16.479097Z","end":"2026-05-09T01:46:16.621831Z","steps":["trace[270153303] 'process raft request' (duration: 132.218116ms)","trace[270153303] 'compare' (duration: 10.310262ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:46:16.622141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.024738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"warn","ts":"2026-05-09T01:46:16.622164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.771172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-05-09T01:46:16.62218Z","caller":"traceutil/trace.go:171","msg":"trace[1318923792] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4904; }","duration":"148.101929ms","start":"2026-05-09T01:46:16.474067Z","end":"2026-05-09T01:46:16.622169Z","steps":["trace[1318923792] 'agreement among raft nodes before linearized reading' (duration: 147.909214ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:46:16.622235Z","caller":"traceutil/trace.go:171","msg":"trace[191843524] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4904; }","duration":"106.888775ms","start":"2026-05-09T01:46:16.515328Z","end":"2026-05-09T01:46:16.622217Z","steps":["trace[191843524] 'agreement among raft nodes before linearized reading' (duration: 106.68007ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:46:21.850858Z","caller":"traceutil/trace.go:171","msg":"trace[1006444344] transaction","detail":"{read_only:false; response_revision:5065; number_of_response:1; }","duration":"109.081455ms","start":"2026-05-09T01:46:21.741751Z","end":"2026-05-09T01:46:21.850833Z","steps":["trace[1006444344] 'process raft request' (duration: 108.9021ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:46:22.153495Z","caller":"traceutil/trace.go:171","msg":"trace[1860237358] transaction","detail":"{read_only:false; response_revision:5066; number_of_response:1; }","duration":"115.645525ms","start":"2026-05-09T01:46:22.037824Z","end":"2026-05-09T01:46:22.15347Z","steps":["trace[1860237358] 'process raft request' (duration: 115.487101ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:46:42.638954Z","caller":"traceutil/trace.go:171","msg":"trace[315750317] transaction","detail":"{read_only:false; response_revision:5495; number_of_response:1; }","duration":"119.161089ms","start":"2026-05-09T01:46:42.519711Z","end":"2026-05-09T01:46:42.638872Z","steps":["trace[315750317] 'process raft request' (duration: 109.360453ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:46:42.639206Z","caller":"traceutil/trace.go:171","msg":"trace[876845174] transaction","detail":"{read_only:false; response_revision:5496; number_of_response:1; }","duration":"119.293733ms","start":"2026-05-09T01:46:42.519879Z","end":"2026-05-09T01:46:42.639173Z","steps":["trace[876845174] 'process raft request' (duration: 118.826691ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:46:42.63953Z","caller":"traceutil/trace.go:171","msg":"trace[1029608803] transaction","detail":"{read_only:false; response_revision:5497; number_of_response:1; }","duration":"119.576381ms","start":"2026-05-09T01:46:42.519908Z","end":"2026-05-09T01:46:42.639484Z","steps":["trace[1029608803] 'process raft request' (duration: 118.954854ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:46:42.63951Z","caller":"traceutil/trace.go:171","msg":"trace[1916643877] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"119.510149ms","start":"2026-05-09T01:46:42.519965Z","end":"2026-05-09T01:46:42.639475Z","steps":["trace[1916643877] 'process raft request' (duration: 119.390846ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:46:42.639816Z","caller":"traceutil/trace.go:171","msg":"trace[1035713772] transaction","detail":"{read_only:false; response_revision:5499; number_of_response:1; }","duration":"119.853909ms","start":"2026-05-09T01:46:42.519945Z","end":"2026-05-09T01:46:42.639799Z","steps":["trace[1035713772] 'process raft request' (duration: 119.454687ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:46:42.640154Z","caller":"traceutil/trace.go:171","msg":"trace[1583195466] transaction","detail":"{read_only:false; response_revision:5500; number_of_response:1; }","duration":"120.118416ms","start":"2026-05-09T01:46:42.520006Z","end":"2026-05-09T01:46:42.640125Z","steps":["trace[1583195466] 'process raft request' (duration: 119.730135ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:47:43.585193Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2964} {"level":"info","ts":"2026-05-09T01:47:43.674458Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2964,"took":"87.060581ms","hash":397454273,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":33533952,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-09T01:47:43.674558Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":397454273,"revision":2964,"compact-revision":-1} {"level":"info","ts":"2026-05-09T01:52:43.593305Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6042} {"level":"info","ts":"2026-05-09T01:52:43.687273Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6042,"took":"91.61562ms","hash":3681138804,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":23695360,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-09T01:52:43.687334Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3681138804,"revision":6042,"compact-revision":2964} {"level":"info","ts":"2026-05-09T01:52:45.653901Z","caller":"traceutil/trace.go:171","msg":"trace[878234039] transaction","detail":"{read_only:false; response_revision:8277; number_of_response:1; }","duration":"129.966979ms","start":"2026-05-09T01:52:45.52389Z","end":"2026-05-09T01:52:45.653857Z","steps":["trace[878234039] 'process raft request' (duration: 129.509507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:52:45.840976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.295639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223774"} {"level":"info","ts":"2026-05-09T01:52:45.841094Z","caller":"traceutil/trace.go:171","msg":"trace[1618686431] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8277; }","duration":"171.463024ms","start":"2026-05-09T01:52:45.669608Z","end":"2026-05-09T01:52:45.841071Z","steps":["trace[1618686431] 'range keys from in-memory index tree' (duration: 170.781405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:52:56.99119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.968113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T01:52:56.991282Z","caller":"traceutil/trace.go:171","msg":"trace[2078056664] transaction","detail":"{read_only:false; response_revision:8333; number_of_response:1; }","duration":"272.836539ms","start":"2026-05-09T01:52:56.718432Z","end":"2026-05-09T01:52:56.991268Z","steps":["trace[2078056664] 'process raft request' (duration: 126.690651ms)","trace[2078056664] 'compare' (duration: 145.86259ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:52:57.078856Z","caller":"traceutil/trace.go:171","msg":"trace[699018624] linearizableReadLoop","detail":"{readStateIndex:8625; appliedIndex:8624; }","duration":"162.904701ms","start":"2026-05-09T01:52:56.915925Z","end":"2026-05-09T01:52:57.078829Z","steps":["trace[699018624] 'read index received' (duration: 162.716266ms)","trace[699018624] 'applied index is now lower than readState.Index' (duration: 186.815µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:52:57.078908Z","caller":"traceutil/trace.go:171","msg":"trace[2094137307] transaction","detail":"{read_only:false; response_revision:8334; number_of_response:1; }","duration":"265.178822ms","start":"2026-05-09T01:52:56.813709Z","end":"2026-05-09T01:52:57.078888Z","steps":["trace[2094137307] 'process raft request' (duration: 264.901775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:52:57.079115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.102845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-09T01:52:57.079151Z","caller":"traceutil/trace.go:171","msg":"trace[1391726707] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8334; }","duration":"150.178838ms","start":"2026-05-09T01:52:56.928963Z","end":"2026-05-09T01:52:57.079142Z","steps":["trace[1391726707] 'agreement among raft nodes before linearized reading' (duration: 150.033024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:52:57.079278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.300392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-09T01:52:57.079409Z","caller":"traceutil/trace.go:171","msg":"trace[820900743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8334; }","duration":"163.499657ms","start":"2026-05-09T01:52:56.915877Z","end":"2026-05-09T01:52:57.079376Z","steps":["trace[820900743] 'agreement among raft nodes before linearized reading' (duration: 163.086446ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:52:57.89482Z","caller":"traceutil/trace.go:171","msg":"trace[1446905260] transaction","detail":"{read_only:false; response_revision:8340; number_of_response:1; }","duration":"179.086829ms","start":"2026-05-09T01:52:57.715701Z","end":"2026-05-09T01:52:57.894787Z","steps":["trace[1446905260] 'process raft request' (duration: 178.925585ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:53:26.740885Z","caller":"traceutil/trace.go:171","msg":"trace[1338370039] linearizableReadLoop","detail":"{readStateIndex:8844; appliedIndex:8843; }","duration":"249.395174ms","start":"2026-05-09T01:53:26.491462Z","end":"2026-05-09T01:53:26.740857Z","steps":["trace[1338370039] 'read index received' (duration: 249.193788ms)","trace[1338370039] 'applied index is now lower than readState.Index' (duration: 200.116µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:53:26.741055Z","caller":"traceutil/trace.go:171","msg":"trace[1362486395] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"253.734181ms","start":"2026-05-09T01:53:26.4873Z","end":"2026-05-09T01:53:26.741034Z","steps":["trace[1362486395] 'process raft request' (duration: 253.369771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:53:26.741217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.720422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:53:26.741284Z","caller":"traceutil/trace.go:171","msg":"trace[1633048232] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8546; }","duration":"249.810295ms","start":"2026-05-09T01:53:26.491456Z","end":"2026-05-09T01:53:26.741266Z","steps":["trace[1633048232] 'agreement among raft nodes before linearized reading' (duration: 249.686512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:53:26.757679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.315091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-05-09T01:53:26.757784Z","caller":"traceutil/trace.go:171","msg":"trace[1027090487] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8547; }","duration":"160.492705ms","start":"2026-05-09T01:53:26.597271Z","end":"2026-05-09T01:53:26.757764Z","steps":["trace[1027090487] 'agreement among raft nodes before linearized reading' (duration: 160.178497ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:53:26.923995Z","caller":"traceutil/trace.go:171","msg":"trace[1446228585] linearizableReadLoop","detail":"{readStateIndex:8846; appliedIndex:8845; }","duration":"159.705345ms","start":"2026-05-09T01:53:26.764256Z","end":"2026-05-09T01:53:26.923961Z","steps":["trace[1446228585] 'read index received' (duration: 67.503318ms)","trace[1446228585] 'applied index is now lower than readState.Index' (duration: 92.200357ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:53:26.924378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.079366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-05-09T01:53:26.924491Z","caller":"traceutil/trace.go:171","msg":"trace[2033571937] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8547; }","duration":"160.249041ms","start":"2026-05-09T01:53:26.764217Z","end":"2026-05-09T01:53:26.924466Z","steps":["trace[2033571937] 'agreement among raft nodes before linearized reading' (duration: 159.864949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:53:29.167737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.50221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:53:29.16786Z","caller":"traceutil/trace.go:171","msg":"trace[1807878765] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:8560; }","duration":"138.717006ms","start":"2026-05-09T01:53:29.029112Z","end":"2026-05-09T01:53:29.167829Z","steps":["trace[1807878765] 'count revisions from in-memory index tree' (duration: 138.377636ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:53:39.336424Z","caller":"traceutil/trace.go:171","msg":"trace[645157869] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"180.363384ms","start":"2026-05-09T01:53:39.156031Z","end":"2026-05-09T01:53:39.336394Z","steps":["trace[645157869] 'process raft request' (duration: 180.186099ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:53:39.337177Z","caller":"traceutil/trace.go:171","msg":"trace[409955694] linearizableReadLoop","detail":"{readStateIndex:8956; appliedIndex:8956; }","duration":"136.30494ms","start":"2026-05-09T01:53:39.200855Z","end":"2026-05-09T01:53:39.33716Z","steps":["trace[409955694] 'read index received' (duration: 136.29694ms)","trace[409955694] 'applied index is now lower than readState.Index' (duration: 6.94µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:53:39.337311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.438345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-cn9z4\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-05-09T01:53:39.337345Z","caller":"traceutil/trace.go:171","msg":"trace[1419055246] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-cn9z4; range_end:; response_count:1; response_revision:8652; }","duration":"136.535428ms","start":"2026-05-09T01:53:39.200799Z","end":"2026-05-09T01:53:39.337335Z","steps":["trace[1419055246] 'agreement among raft nodes before linearized reading' (duration: 136.444955ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:53:39.350433Z","caller":"traceutil/trace.go:171","msg":"trace[712167473] transaction","detail":"{read_only:false; response_revision:8653; number_of_response:1; }","duration":"149.18567ms","start":"2026-05-09T01:53:39.201212Z","end":"2026-05-09T01:53:39.350398Z","steps":["trace[712167473] 'process raft request' (duration: 148.782179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:53:39.350907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.517093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:53:39.35096Z","caller":"traceutil/trace.go:171","msg":"trace[1198855296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8653; }","duration":"100.589315ms","start":"2026-05-09T01:53:39.250359Z","end":"2026-05-09T01:53:39.350948Z","steps":["trace[1198855296] 'agreement among raft nodes before linearized reading' (duration: 100.453311ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:53:40.24462Z","caller":"traceutil/trace.go:171","msg":"trace[1039230212] transaction","detail":"{read_only:false; response_revision:8660; number_of_response:1; }","duration":"107.302256ms","start":"2026-05-09T01:53:40.137278Z","end":"2026-05-09T01:53:40.24458Z","steps":["trace[1039230212] 'process raft request' (duration: 107.063619ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:53:40.33464Z","caller":"traceutil/trace.go:171","msg":"trace[2008533753] linearizableReadLoop","detail":"{readStateIndex:8965; appliedIndex:8963; }","duration":"122.362964ms","start":"2026-05-09T01:53:40.212239Z","end":"2026-05-09T01:53:40.334602Z","steps":["trace[2008533753] 'read index received' (duration: 32.093829ms)","trace[2008533753] 'applied index is now lower than readState.Index' (duration: 90.267385ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:53:40.334691Z","caller":"traceutil/trace.go:171","msg":"trace[257621641] transaction","detail":"{read_only:false; response_revision:8661; number_of_response:1; }","duration":"194.60166ms","start":"2026-05-09T01:53:40.140044Z","end":"2026-05-09T01:53:40.334645Z","steps":["trace[257621641] 'process raft request' (duration: 194.25282ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:53:40.334718Z","caller":"traceutil/trace.go:171","msg":"trace[95807843] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"105.781705ms","start":"2026-05-09T01:53:40.228898Z","end":"2026-05-09T01:53:40.33468Z","steps":["trace[95807843] 'process raft request' (duration: 105.632481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:53:40.335217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.926199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rbc4q\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-09T01:53:40.335345Z","caller":"traceutil/trace.go:171","msg":"trace[1127578765] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rbc4q; range_end:; response_count:1; response_revision:8662; }","duration":"123.128794ms","start":"2026-05-09T01:53:40.212186Z","end":"2026-05-09T01:53:40.335315Z","steps":["trace[1127578765] 'agreement among raft nodes before linearized reading' (duration: 122.581ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:54:22.329594Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002491.wal"} {"level":"warn","ts":"2026-05-09T01:54:54.990149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.606741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-09T01:54:54.990266Z","caller":"traceutil/trace.go:171","msg":"trace[599616123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9401; }","duration":"157.799386ms","start":"2026-05-09T01:54:54.832442Z","end":"2026-05-09T01:54:54.990242Z","steps":["trace[599616123] 'range keys from in-memory index tree' (duration: 157.383985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:54:54.99028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.316307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rbc4q\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-09T01:54:54.9904Z","caller":"traceutil/trace.go:171","msg":"trace[907809789] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rbc4q; range_end:; response_count:1; response_revision:9401; }","duration":"149.501222ms","start":"2026-05-09T01:54:54.840874Z","end":"2026-05-09T01:54:54.990375Z","steps":["trace[907809789] 'range keys from in-memory index tree' (duration: 149.09005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:54:54.990567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.762751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:54:54.99062Z","caller":"traceutil/trace.go:171","msg":"trace[1042744574] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:9401; }","duration":"206.857423ms","start":"2026-05-09T01:54:54.783747Z","end":"2026-05-09T01:54:54.990604Z","steps":["trace[1042744574] 'count revisions from in-memory index tree' (duration: 206.654257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:54:54.990842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.719493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-09T01:54:54.990946Z","caller":"traceutil/trace.go:171","msg":"trace[580418098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9401; }","duration":"137.849906ms","start":"2026-05-09T01:54:54.853069Z","end":"2026-05-09T01:54:54.990919Z","steps":["trace[580418098] 'range keys from in-memory index tree' (duration: 137.549848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:54:54.990842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.249357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-7c68594cff-fznvd.18adc2bfc836a083\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-05-09T01:54:54.991099Z","caller":"traceutil/trace.go:171","msg":"trace[708077327] range","detail":"{range_begin:/registry/events/openstack/barbican-api-7c68594cff-fznvd.18adc2bfc836a083; range_end:; response_count:1; response_revision:9401; }","duration":"155.531445ms","start":"2026-05-09T01:54:54.835512Z","end":"2026-05-09T01:54:54.991043Z","steps":["trace[708077327] 'range keys from in-memory index tree' (duration: 155.078732ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:55:30.163955Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a847c2e9c7010db6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T01:55:30.170229Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-09T01:55:30.170354Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-05-09T01:57:12.874793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.78302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-05-09T01:57:12.874902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.315287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:57:12.87496Z","caller":"traceutil/trace.go:171","msg":"trace[191316432] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10450; }","duration":"112.966845ms","start":"2026-05-09T01:57:12.761918Z","end":"2026-05-09T01:57:12.874884Z","steps":["trace[191316432] 'range keys from in-memory index tree' (duration: 112.533483ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:57:12.875001Z","caller":"traceutil/trace.go:171","msg":"trace[386818978] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:10450; }","duration":"200.470541ms","start":"2026-05-09T01:57:12.674507Z","end":"2026-05-09T01:57:12.874977Z","steps":["trace[386818978] 'count revisions from in-memory index tree' (duration: 200.221785ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:57:43.599953Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8266} {"level":"info","ts":"2026-05-09T01:57:43.688329Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8266,"took":"85.979486ms","hash":4284661969,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":26279936,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-09T01:57:43.68841Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4284661969,"revision":8266,"compact-revision":6042} {"level":"info","ts":"2026-05-09T01:58:30.86047Z","caller":"traceutil/trace.go:171","msg":"trace[1921558483] transaction","detail":"{read_only:false; response_revision:11212; number_of_response:1; }","duration":"291.214755ms","start":"2026-05-09T01:58:30.569221Z","end":"2026-05-09T01:58:30.860436Z","steps":["trace[1921558483] 'process raft request' (duration: 290.972849ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:58:30.860935Z","caller":"traceutil/trace.go:171","msg":"trace[726748053] linearizableReadLoop","detail":"{readStateIndex:11590; appliedIndex:11588; }","duration":"241.906823ms","start":"2026-05-09T01:58:30.619011Z","end":"2026-05-09T01:58:30.860918Z","steps":["trace[726748053] 'read index received' (duration: 241.189163ms)","trace[726748053] 'applied index is now lower than readState.Index' (duration: 717.28µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:58:30.861103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.077028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rbc4q\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-09T01:58:30.861124Z","caller":"traceutil/trace.go:171","msg":"trace[869602509] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rbc4q; range_end:; response_count:1; response_revision:11213; }","duration":"242.12236ms","start":"2026-05-09T01:58:30.618995Z","end":"2026-05-09T01:58:30.861117Z","steps":["trace[869602509] 'agreement among raft nodes before linearized reading' (duration: 242.001066ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:58:30.861125Z","caller":"traceutil/trace.go:171","msg":"trace[652336931] transaction","detail":"{read_only:false; response_revision:11213; number_of_response:1; }","duration":"291.670288ms","start":"2026-05-09T01:58:30.569441Z","end":"2026-05-09T01:58:30.861111Z","steps":["trace[652336931] 'process raft request' (duration: 291.383311ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:58:34.694757Z","caller":"traceutil/trace.go:171","msg":"trace[1354244076] transaction","detail":"{read_only:false; response_revision:11231; number_of_response:1; }","duration":"123.552252ms","start":"2026-05-09T01:58:34.571163Z","end":"2026-05-09T01:58:34.694715Z","steps":["trace[1354244076] 'process raft request' (duration: 123.231783ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:58:37.354667Z","caller":"traceutil/trace.go:171","msg":"trace[481261261] linearizableReadLoop","detail":"{readStateIndex:11627; appliedIndex:11625; }","duration":"101.926328ms","start":"2026-05-09T01:58:37.252712Z","end":"2026-05-09T01:58:37.354638Z","steps":["trace[481261261] 'read index received' (duration: 24.036331ms)","trace[481261261] 'applied index is now lower than readState.Index' (duration: 77.888477ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:58:37.35483Z","caller":"traceutil/trace.go:171","msg":"trace[1345604207] transaction","detail":"{read_only:false; response_revision:11248; number_of_response:1; }","duration":"146.233446ms","start":"2026-05-09T01:58:37.208502Z","end":"2026-05-09T01:58:37.354735Z","steps":["trace[1345604207] 'process raft request' (duration: 99.719577ms)","trace[1345604207] 'compare' (duration: 46.287703ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:58:37.354879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.143993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:58:37.355083Z","caller":"traceutil/trace.go:171","msg":"trace[1677867163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11248; }","duration":"102.37007ms","start":"2026-05-09T01:58:37.252676Z","end":"2026-05-09T01:58:37.355046Z","steps":["trace[1677867163] 'agreement among raft nodes before linearized reading' (duration: 102.125963ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:02:43.609391Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10757} {"level":"info","ts":"2026-05-09T02:02:43.696577Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10757,"took":"81.676439ms","hash":4145080234,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":27267072,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-09T02:02:43.696662Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4145080234,"revision":10757,"compact-revision":8266} {"level":"info","ts":"2026-05-09T02:04:45.27518Z","caller":"traceutil/trace.go:171","msg":"trace[407899623] linearizableReadLoop","detail":"{readStateIndex:14203; appliedIndex:14202; }","duration":"105.107839ms","start":"2026-05-09T02:04:45.170044Z","end":"2026-05-09T02:04:45.275152Z","steps":["trace[407899623] 'read index received' (duration: 104.860402ms)","trace[407899623] 'applied index is now lower than readState.Index' (duration: 245.616µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:04:45.275474Z","caller":"traceutil/trace.go:171","msg":"trace[154013519] transaction","detail":"{read_only:false; response_revision:13737; number_of_response:1; }","duration":"112.20965ms","start":"2026-05-09T02:04:45.163251Z","end":"2026-05-09T02:04:45.27546Z","steps":["trace[154013519] 'process raft request' (duration: 111.748517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:04:45.27572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.636973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T02:04:45.275806Z","caller":"traceutil/trace.go:171","msg":"trace[1725212189] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:13737; }","duration":"105.767346ms","start":"2026-05-09T02:04:45.170018Z","end":"2026-05-09T02:04:45.275786Z","steps":["trace[1725212189] 'agreement among raft nodes before linearized reading' (duration: 105.587372ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:04:45.391512Z","caller":"traceutil/trace.go:171","msg":"trace[1357686919] transaction","detail":"{read_only:false; response_revision:13738; number_of_response:1; }","duration":"110.36122ms","start":"2026-05-09T02:04:45.281111Z","end":"2026-05-09T02:04:45.391472Z","steps":["trace[1357686919] 'process raft request' (duration: 108.626454ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:05:00.543711Z","caller":"traceutil/trace.go:171","msg":"trace[1032645490] transaction","detail":"{read_only:false; response_revision:13839; number_of_response:1; }","duration":"103.31046ms","start":"2026-05-09T02:05:00.440369Z","end":"2026-05-09T02:05:00.543679Z","steps":["trace[1032645490] 'process raft request' (duration: 102.94132ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:05:00.659287Z","caller":"traceutil/trace.go:171","msg":"trace[1575976027] transaction","detail":"{read_only:false; response_revision:13841; number_of_response:1; }","duration":"112.165709ms","start":"2026-05-09T02:05:00.547087Z","end":"2026-05-09T02:05:00.659253Z","steps":["trace[1575976027] 'process raft request' (duration: 111.953553ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:05:00.660858Z","caller":"traceutil/trace.go:171","msg":"trace[167352032] transaction","detail":"{read_only:false; response_revision:13842; number_of_response:1; }","duration":"106.785264ms","start":"2026-05-09T02:05:00.553671Z","end":"2026-05-09T02:05:00.660456Z","steps":["trace[167352032] 'process raft request' (duration: 105.501469ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:05:01.345023Z","caller":"traceutil/trace.go:171","msg":"trace[1920603171] transaction","detail":"{read_only:false; response_revision:13847; number_of_response:1; }","duration":"124.361698ms","start":"2026-05-09T02:05:01.220619Z","end":"2026-05-09T02:05:01.344981Z","steps":["trace[1920603171] 'process raft request' (duration: 124.099701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:05:01.529181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.325417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-09T02:05:01.529263Z","caller":"traceutil/trace.go:171","msg":"trace[1617397864] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13847; }","duration":"134.549633ms","start":"2026-05-09T02:05:01.394695Z","end":"2026-05-09T02:05:01.529244Z","steps":["trace[1617397864] 'range keys from in-memory index tree' (duration: 134.123082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:05:01.529607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.033004ms","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-05-09T02:05:01.529704Z","caller":"traceutil/trace.go:171","msg":"trace[139381640] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:13847; }","duration":"109.149698ms","start":"2026-05-09T02:05:01.420509Z","end":"2026-05-09T02:05:01.529658Z","steps":["trace[139381640] 'range keys from in-memory index tree' (duration: 108.888851ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:07:43.618782Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12828} {"level":"info","ts":"2026-05-09T02:07:43.691761Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12828,"took":"70.831824ms","hash":4273136382,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":30973952,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-09T02:07:43.691817Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4273136382,"revision":12828,"compact-revision":10757} {"level":"info","ts":"2026-05-09T02:12:32.476517Z","caller":"traceutil/trace.go:171","msg":"trace[1646532299] transaction","detail":"{read_only:false; response_revision:17894; number_of_response:1; }","duration":"176.555001ms","start":"2026-05-09T02:12:32.299937Z","end":"2026-05-09T02:12:32.476492Z","steps":["trace[1646532299] 'process raft request' (duration: 84.732989ms)","trace[1646532299] 'compare' (duration: 91.501493ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:12:32.476777Z","caller":"traceutil/trace.go:171","msg":"trace[1646544695] transaction","detail":"{read_only:false; response_revision:17895; number_of_response:1; }","duration":"166.216131ms","start":"2026-05-09T02:12:32.310555Z","end":"2026-05-09T02:12:32.476771Z","steps":["trace[1646544695] 'process raft request' (duration: 165.887042ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:12:33.016291Z","caller":"traceutil/trace.go:171","msg":"trace[693461377] transaction","detail":"{read_only:false; response_revision:17896; number_of_response:1; }","duration":"281.482706ms","start":"2026-05-09T02:12:32.734785Z","end":"2026-05-09T02:12:33.016268Z","steps":["trace[693461377] 'process raft request' (duration: 266.623344ms)","trace[693461377] 'compare' (duration: 14.451061ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:12:33.016276Z","caller":"traceutil/trace.go:171","msg":"trace[546104215] linearizableReadLoop","detail":"{readStateIndex:18490; appliedIndex:18489; }","duration":"230.93823ms","start":"2026-05-09T02:12:32.785309Z","end":"2026-05-09T02:12:33.016247Z","steps":["trace[546104215] 'read index received' (duration: 216.059218ms)","trace[546104215] 'applied index is now lower than readState.Index' (duration: 14.877682ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:12:33.016619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.042136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T02:12:33.016684Z","caller":"traceutil/trace.go:171","msg":"trace[141663190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17896; }","duration":"173.163189ms","start":"2026-05-09T02:12:32.843504Z","end":"2026-05-09T02:12:33.016667Z","steps":["trace[141663190] 'agreement among raft nodes before linearized reading' (duration: 172.957713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:12:33.016719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.393403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-rt8f9\" ","response":"range_response_count:1 size:9796"} {"level":"warn","ts":"2026-05-09T02:12:33.016749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.547993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-09T02:12:33.016824Z","caller":"traceutil/trace.go:171","msg":"trace[491259031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17896; }","duration":"172.658575ms","start":"2026-05-09T02:12:32.844148Z","end":"2026-05-09T02:12:33.016807Z","steps":["trace[491259031] 'agreement among raft nodes before linearized reading' (duration: 172.44106ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:12:33.016828Z","caller":"traceutil/trace.go:171","msg":"trace[1017521697] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-rt8f9; range_end:; response_count:1; response_revision:17896; }","duration":"231.532826ms","start":"2026-05-09T02:12:32.785267Z","end":"2026-05-09T02:12:33.0168Z","steps":["trace[1017521697] 'agreement among raft nodes before linearized reading' (duration: 231.088134ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:12:43.625356Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15463} {"level":"info","ts":"2026-05-09T02:12:43.727919Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15463,"took":"99.359384ms","hash":3394958128,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":33894400,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-09T02:12:43.72798Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3394958128,"revision":15463,"compact-revision":12828} {"level":"info","ts":"2026-05-09T02:15:52.282621Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a847c2e9c7010db6","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T02:15:52.290921Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-09T02:15:52.291076Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-09T02:17:25.941251Z","caller":"traceutil/trace.go:171","msg":"trace[714277247] linearizableReadLoop","detail":"{readStateIndex:20588; appliedIndex:20587; }","duration":"254.653127ms","start":"2026-05-09T02:17:25.686572Z","end":"2026-05-09T02:17:25.941225Z","steps":["trace[714277247] 'read index received' (duration: 254.506853ms)","trace[714277247] 'applied index is now lower than readState.Index' (duration: 145.124µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:17:25.941302Z","caller":"traceutil/trace.go:171","msg":"trace[1773375939] transaction","detail":"{read_only:false; response_revision:19924; number_of_response:1; }","duration":"286.750893ms","start":"2026-05-09T02:17:25.654517Z","end":"2026-05-09T02:17:25.941268Z","steps":["trace[1773375939] 'process raft request' (duration: 286.526497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:17:25.941409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.821281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:17:25.941551Z","caller":"traceutil/trace.go:171","msg":"trace[1610618031] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:19924; }","duration":"255.008666ms","start":"2026-05-09T02:17:25.686527Z","end":"2026-05-09T02:17:25.941536Z","steps":["trace[1610618031] 'agreement among raft nodes before linearized reading' (duration: 254.825961ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:17:26.000289Z","caller":"traceutil/trace.go:171","msg":"trace[932373545] transaction","detail":"{read_only:false; response_revision:19925; number_of_response:1; }","duration":"128.838549ms","start":"2026-05-09T02:17:25.871417Z","end":"2026-05-09T02:17:26.000255Z","steps":["trace[932373545] 'process raft request' (duration: 128.651064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:17:26.000334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.773277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T02:17:26.000534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.927511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T02:17:26.000614Z","caller":"traceutil/trace.go:171","msg":"trace[1245356009] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19925; }","duration":"259.041805ms","start":"2026-05-09T02:17:25.741552Z","end":"2026-05-09T02:17:26.000594Z","steps":["trace[1245356009] 'agreement among raft nodes before linearized reading' (duration: 258.815689ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:17:26.000584Z","caller":"traceutil/trace.go:171","msg":"trace[1871996290] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:19925; }","duration":"169.078045ms","start":"2026-05-09T02:17:25.831483Z","end":"2026-05-09T02:17:26.000561Z","steps":["trace[1871996290] 'agreement among raft nodes before linearized reading' (duration: 168.731116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:17:26.000694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.105326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-09T02:17:26.000783Z","caller":"traceutil/trace.go:171","msg":"trace[273358452] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19925; }","duration":"239.25473ms","start":"2026-05-09T02:17:25.761503Z","end":"2026-05-09T02:17:26.000758Z","steps":["trace[273358452] 'agreement among raft nodes before linearized reading' (duration: 238.734936ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:17:36.267702Z","caller":"traceutil/trace.go:171","msg":"trace[100645849] transaction","detail":"{read_only:false; response_revision:19982; number_of_response:1; }","duration":"114.495102ms","start":"2026-05-09T02:17:36.153183Z","end":"2026-05-09T02:17:36.267678Z","steps":["trace[100645849] 'process raft request' (duration: 109.538718ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:17:41.788896Z","caller":"traceutil/trace.go:171","msg":"trace[1481559111] transaction","detail":"{read_only:false; response_revision:20006; number_of_response:1; }","duration":"108.311465ms","start":"2026-05-09T02:17:41.680563Z","end":"2026-05-09T02:17:41.788875Z","steps":["trace[1481559111] 'process raft request' (duration: 108.178492ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:17:41.801758Z","caller":"traceutil/trace.go:171","msg":"trace[1807446671] transaction","detail":"{read_only:false; response_revision:20007; number_of_response:1; }","duration":"119.295132ms","start":"2026-05-09T02:17:41.682434Z","end":"2026-05-09T02:17:41.801729Z","steps":["trace[1807446671] 'process raft request' (duration: 119.171179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:17:42.381025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.697938ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-09T02:17:42.381273Z","caller":"traceutil/trace.go:171","msg":"trace[1743941715] transaction","detail":"{read_only:false; response_revision:20010; number_of_response:1; }","duration":"239.501168ms","start":"2026-05-09T02:17:42.141755Z","end":"2026-05-09T02:17:42.381256Z","steps":["trace[1743941715] 'process raft request' (duration: 239.372974ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:17:42.381312Z","caller":"traceutil/trace.go:171","msg":"trace[1163616608] linearizableReadLoop","detail":"{readStateIndex:20678; appliedIndex:20677; }","duration":"244.878643ms","start":"2026-05-09T02:17:42.136347Z","end":"2026-05-09T02:17:42.381225Z","steps":["trace[1163616608] 'read index received' (duration: 86.993129ms)","trace[1163616608] 'applied index is now lower than readState.Index' (duration: 157.882114ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:17:42.381643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.627776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:17:42.381694Z","caller":"traceutil/trace.go:171","msg":"trace[1345045837] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:20010; }","duration":"257.732699ms","start":"2026-05-09T02:17:42.123945Z","end":"2026-05-09T02:17:42.381678Z","steps":["trace[1345045837] 'agreement among raft nodes before linearized reading' (duration: 257.569075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:17:42.381918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.751299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-09T02:17:42.381988Z","caller":"traceutil/trace.go:171","msg":"trace[1929081358] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20010; }","duration":"294.866132ms","start":"2026-05-09T02:17:42.087101Z","end":"2026-05-09T02:17:42.381968Z","steps":["trace[1929081358] 'agreement among raft nodes before linearized reading' (duration: 294.642866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:17:42.640032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.864557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T02:17:42.640146Z","caller":"traceutil/trace.go:171","msg":"trace[1450185389] linearizableReadLoop","detail":"{readStateIndex:20680; appliedIndex:20679; }","duration":"258.664555ms","start":"2026-05-09T02:17:42.381464Z","end":"2026-05-09T02:17:42.640128Z","steps":["trace[1450185389] 'read index received' (duration: 145.52167ms)","trace[1450185389] 'applied index is now lower than readState.Index' (duration: 113.141385ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:17:42.64022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.807079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:17:42.640208Z","caller":"traceutil/trace.go:171","msg":"trace[1941860276] transaction","detail":"{read_only:false; response_revision:20011; number_of_response:1; }","duration":"307.174335ms","start":"2026-05-09T02:17:42.332968Z","end":"2026-05-09T02:17:42.640143Z","steps":["trace[1941860276] 'process raft request' (duration: 194.097592ms)","trace[1941860276] 'compare' (duration: 112.555549ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:17:42.640253Z","caller":"traceutil/trace.go:171","msg":"trace[1229088964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20011; }","duration":"388.888371ms","start":"2026-05-09T02:17:42.251355Z","end":"2026-05-09T02:17:42.640243Z","steps":["trace[1229088964] 'agreement among raft nodes before linearized reading' (duration: 388.820269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:17:42.640289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:17:42.251332Z","time spent":"388.950673ms","remote":"127.0.0.1:59002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T02:17:42.640426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.633875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-n9xwh\" ","response":"range_response_count:1 size:22122"} {"level":"info","ts":"2026-05-09T02:17:42.640506Z","caller":"traceutil/trace.go:171","msg":"trace[582120250] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-n9xwh; range_end:; response_count:1; response_revision:20011; }","duration":"388.744627ms","start":"2026-05-09T02:17:42.251741Z","end":"2026-05-09T02:17:42.640486Z","steps":["trace[582120250] 'agreement among raft nodes before linearized reading' (duration: 388.47019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:17:42.640517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:17:42.332947Z","time spent":"307.338829ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:17:42.640586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:17:42.25173Z","time spent":"388.84514ms","remote":"127.0.0.1:59184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22145,"request content":"key:\"/registry/pods/openstack/nova-compute-default-n9xwh\" "} {"level":"info","ts":"2026-05-09T02:17:42.672585Z","caller":"traceutil/trace.go:171","msg":"trace[1399163685] transaction","detail":"{read_only:false; response_revision:20012; number_of_response:1; }","duration":"286.322433ms","start":"2026-05-09T02:17:42.3862Z","end":"2026-05-09T02:17:42.672522Z","steps":["trace[1399163685] 'process raft request' (duration: 286.062595ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:17:42.881923Z","caller":"traceutil/trace.go:171","msg":"trace[1886612258] linearizableReadLoop","detail":"{readStateIndex:20682; appliedIndex:20681; }","duration":"187.73596ms","start":"2026-05-09T02:17:42.694159Z","end":"2026-05-09T02:17:42.881895Z","steps":["trace[1886612258] 'read index received' (duration: 92.767685ms)","trace[1886612258] 'applied index is now lower than readState.Index' (duration: 94.967285ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:17:42.881965Z","caller":"traceutil/trace.go:171","msg":"trace[622784232] transaction","detail":"{read_only:false; response_revision:20013; number_of_response:1; }","duration":"203.441903ms","start":"2026-05-09T02:17:42.678482Z","end":"2026-05-09T02:17:42.881924Z","steps":["trace[622784232] 'process raft request' (duration: 108.383596ms)","trace[622784232] 'compare' (duration: 94.781949ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:17:42.882151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.975056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T02:17:42.882211Z","caller":"traceutil/trace.go:171","msg":"trace[2039205695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20013; }","duration":"188.082419ms","start":"2026-05-09T02:17:42.694117Z","end":"2026-05-09T02:17:42.8822Z","steps":["trace[2039205695] 'agreement among raft nodes before linearized reading' (duration: 187.896424ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:17:43.632746Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18002} {"level":"info","ts":"2026-05-09T02:17:43.767577Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18002,"took":"131.48791ms","hash":706927306,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":28753920,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-09T02:17:43.767645Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":706927306,"revision":18002,"compact-revision":15463} {"level":"info","ts":"2026-05-09T02:21:23.087795Z","caller":"traceutil/trace.go:171","msg":"trace[710401743] linearizableReadLoop","detail":"{readStateIndex:23115; appliedIndex:23113; }","duration":"167.011639ms","start":"2026-05-09T02:21:22.92075Z","end":"2026-05-09T02:21:23.087762Z","steps":["trace[710401743] 'read index received' (duration: 28.451598ms)","trace[710401743] 'applied index is now lower than readState.Index' (duration: 138.557961ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:21:23.087961Z","caller":"traceutil/trace.go:171","msg":"trace[1922415056] transaction","detail":"{read_only:false; response_revision:22374; number_of_response:1; }","duration":"210.582466ms","start":"2026-05-09T02:21:22.87733Z","end":"2026-05-09T02:21:23.087913Z","steps":["trace[1922415056] 'process raft request' (duration: 201.302886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:21:23.088018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.227295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T02:21:23.088077Z","caller":"traceutil/trace.go:171","msg":"trace[612820264] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:22374; }","duration":"167.360958ms","start":"2026-05-09T02:21:22.920696Z","end":"2026-05-09T02:21:23.088057Z","steps":["trace[612820264] 'agreement among raft nodes before linearized reading' (duration: 167.203334ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:22:43.640941Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20016} {"level":"info","ts":"2026-05-09T02:22:43.744484Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20016,"took":"100.72401ms","hash":3089410991,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":41336832,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-05-09T02:22:43.744556Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3089410991,"revision":20016,"compact-revision":18002} {"level":"info","ts":"2026-05-09T02:24:39.015388Z","caller":"traceutil/trace.go:171","msg":"trace[557153706] transaction","detail":"{read_only:false; response_revision:24356; number_of_response:1; }","duration":"267.231972ms","start":"2026-05-09T02:24:38.748119Z","end":"2026-05-09T02:24:39.015351Z","steps":["trace[557153706] 'process raft request' (duration: 217.909761ms)","trace[557153706] 'compare' (duration: 49.179167ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:39.015357Z","caller":"traceutil/trace.go:171","msg":"trace[1606938151] linearizableReadLoop","detail":"{readStateIndex:25148; appliedIndex:25147; }","duration":"222.856726ms","start":"2026-05-09T02:24:38.792474Z","end":"2026-05-09T02:24:39.015331Z","steps":["trace[1606938151] 'read index received' (duration: 173.559745ms)","trace[1606938151] 'applied index is now lower than readState.Index' (duration: 49.29569ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:24:39.015607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.119332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T02:24:39.015669Z","caller":"traceutil/trace.go:171","msg":"trace[1123544608] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24356; }","duration":"223.233965ms","start":"2026-05-09T02:24:38.792417Z","end":"2026-05-09T02:24:39.015651Z","steps":["trace[1123544608] 'agreement among raft nodes before linearized reading' (duration: 223.02828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:39.350435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.77345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T02:24:39.350578Z","caller":"traceutil/trace.go:171","msg":"trace[2094944586] transaction","detail":"{read_only:false; response_revision:24358; number_of_response:1; }","duration":"330.827349ms","start":"2026-05-09T02:24:39.019736Z","end":"2026-05-09T02:24:39.350564Z","steps":["trace[2094944586] 'process raft request' (duration: 330.788998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:39.350628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:39.019717Z","time spent":"330.890631ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T02:24:39.35088Z","caller":"traceutil/trace.go:171","msg":"trace[1001858342] linearizableReadLoop","detail":"{readStateIndex:25149; appliedIndex:25148; }","duration":"335.29371ms","start":"2026-05-09T02:24:39.015515Z","end":"2026-05-09T02:24:39.350809Z","steps":["trace[1001858342] 'read index received' (duration: 138.029935ms)","trace[1001858342] 'applied index is now lower than readState.Index' (duration: 197.260015ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:39.350951Z","caller":"traceutil/trace.go:171","msg":"trace[2019945521] transaction","detail":"{read_only:false; response_revision:24357; number_of_response:1; }","duration":"470.22569ms","start":"2026-05-09T02:24:38.880716Z","end":"2026-05-09T02:24:39.350942Z","steps":["trace[2019945521] 'process raft request' (duration: 272.865593ms)","trace[2019945521] 'compare' (duration: 196.670668ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:24:39.353404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:38.880695Z","time spent":"472.620025ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:24:39.351008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.386794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:24:39.353616Z","caller":"traceutil/trace.go:171","msg":"trace[1706710742] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:24358; }","duration":"426.990334ms","start":"2026-05-09T02:24:38.926584Z","end":"2026-05-09T02:24:39.353575Z","steps":["trace[1706710742] 'agreement among raft nodes before linearized reading' (duration: 424.394754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:39.353772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:38.926573Z","time spent":"427.142068ms","remote":"127.0.0.1:41752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true "} {"level":"warn","ts":"2026-05-09T02:24:39.35105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.494941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:24:39.353989Z","caller":"traceutil/trace.go:171","msg":"trace[348819547] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:24358; }","duration":"485.41422ms","start":"2026-05-09T02:24:38.868548Z","end":"2026-05-09T02:24:39.353962Z","steps":["trace[348819547] 'agreement among raft nodes before linearized reading' (duration: 482.482351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:39.354023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.592817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-09T02:24:39.354043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:38.868524Z","time spent":"485.501262ms","remote":"127.0.0.1:45230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"info","ts":"2026-05-09T02:24:39.354063Z","caller":"traceutil/trace.go:171","msg":"trace[467951057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24358; }","duration":"262.669169ms","start":"2026-05-09T02:24:39.091381Z","end":"2026-05-09T02:24:39.354051Z","steps":["trace[467951057] 'agreement among raft nodes before linearized reading' (duration: 262.556396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:39.354188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.328859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T02:24:39.354219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.844908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:24:39.354226Z","caller":"traceutil/trace.go:171","msg":"trace[1369247612] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:24358; }","duration":"126.395061ms","start":"2026-05-09T02:24:39.227821Z","end":"2026-05-09T02:24:39.354216Z","steps":["trace[1369247612] 'agreement among raft nodes before linearized reading' (duration: 126.309329ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:39.354246Z","caller":"traceutil/trace.go:171","msg":"trace[1954287261] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:24358; }","duration":"234.90036ms","start":"2026-05-09T02:24:39.119337Z","end":"2026-05-09T02:24:39.354237Z","steps":["trace[1954287261] 'agreement among raft nodes before linearized reading' (duration: 234.861909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:39.354395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.637877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T02:24:39.354416Z","caller":"traceutil/trace.go:171","msg":"trace[504276722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24358; }","duration":"256.680118ms","start":"2026-05-09T02:24:39.097728Z","end":"2026-05-09T02:24:39.354409Z","steps":["trace[504276722] 'agreement among raft nodes before linearized reading' (duration: 256.622216ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:39.681364Z","caller":"traceutil/trace.go:171","msg":"trace[1817467243] transaction","detail":"{read_only:false; response_revision:24359; number_of_response:1; }","duration":"323.808659ms","start":"2026-05-09T02:24:39.357531Z","end":"2026-05-09T02:24:39.68134Z","steps":["trace[1817467243] 'process raft request' (duration: 323.673256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:39.68188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:39.357509Z","time spent":"323.931203ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:24:39.915334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.743489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T02:24:39.915695Z","caller":"traceutil/trace.go:171","msg":"trace[500964920] linearizableReadLoop","detail":"{readStateIndex:25152; appliedIndex:25151; }","duration":"556.817738ms","start":"2026-05-09T02:24:39.358825Z","end":"2026-05-09T02:24:39.915643Z","steps":["trace[500964920] 'read index received' (duration: 322.507314ms)","trace[500964920] 'applied index is now lower than readState.Index' (duration: 234.304653ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:39.915711Z","caller":"traceutil/trace.go:171","msg":"trace[893437846] transaction","detail":"{read_only:false; response_revision:24360; number_of_response:1; }","duration":"557.645ms","start":"2026-05-09T02:24:39.35799Z","end":"2026-05-09T02:24:39.915635Z","steps":["trace[893437846] 'process raft request' (duration: 406.510081ms)","trace[893437846] 'compare' (duration: 150.207105ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:24:39.91609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.511464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:24:39.916171Z","caller":"traceutil/trace.go:171","msg":"trace[746027763] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:24362; }","duration":"136.670768ms","start":"2026-05-09T02:24:39.779479Z","end":"2026-05-09T02:24:39.916149Z","steps":["trace[746027763] 'agreement among raft nodes before linearized reading' (duration: 136.465553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:39.91605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:39.357975Z","time spent":"557.938548ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:24:39.916189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.314261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-05-09T02:24:39.916344Z","caller":"traceutil/trace.go:171","msg":"trace[2025848301] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24362; }","duration":"557.516667ms","start":"2026-05-09T02:24:39.358809Z","end":"2026-05-09T02:24:39.916325Z","steps":["trace[2025848301] 'agreement among raft nodes before linearized reading' (duration: 557.027673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:39.916423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:39.358802Z","time spent":"557.574168ms","remote":"127.0.0.1:59444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11860,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-05-09T02:24:39.916499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.554719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-09T02:24:39.916717Z","caller":"traceutil/trace.go:171","msg":"trace[751145759] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24362; }","duration":"305.168947ms","start":"2026-05-09T02:24:39.611527Z","end":"2026-05-09T02:24:39.916696Z","steps":["trace[751145759] 'agreement among raft nodes before linearized reading' (duration: 304.481537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:39.916842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:39.61145Z","time spent":"305.373622ms","remote":"127.0.0.1:59184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-09T02:24:40.180964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.73944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T02:24:40.181077Z","caller":"traceutil/trace.go:171","msg":"trace[1063146068] linearizableReadLoop","detail":"{readStateIndex:25155; appliedIndex:25154; }","duration":"180.482001ms","start":"2026-05-09T02:24:40.000554Z","end":"2026-05-09T02:24:40.181036Z","steps":["trace[1063146068] 'read index received' (duration: 33.769251ms)","trace[1063146068] 'applied index is now lower than readState.Index' (duration: 146.71154ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:40.181154Z","caller":"traceutil/trace.go:171","msg":"trace[995226256] transaction","detail":"{read_only:false; response_revision:24363; number_of_response:1; }","duration":"245.410903ms","start":"2026-05-09T02:24:39.935731Z","end":"2026-05-09T02:24:40.181142Z","steps":["trace[995226256] 'process raft request' (duration: 98.420207ms)","trace[995226256] 'compare' (duration: 146.435582ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:24:40.181261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.706306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:24:40.181295Z","caller":"traceutil/trace.go:171","msg":"trace[198801379] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24363; }","duration":"180.775128ms","start":"2026-05-09T02:24:40.000509Z","end":"2026-05-09T02:24:40.181284Z","steps":["trace[198801379] 'agreement among raft nodes before linearized reading' (duration: 180.715417ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:40.214987Z","caller":"traceutil/trace.go:171","msg":"trace[1123078898] transaction","detail":"{read_only:false; response_revision:24364; number_of_response:1; }","duration":"211.720494ms","start":"2026-05-09T02:24:40.003241Z","end":"2026-05-09T02:24:40.214961Z","steps":["trace[1123078898] 'process raft request' (duration: 211.541839ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:40.236389Z","caller":"traceutil/trace.go:171","msg":"trace[1220609737] transaction","detail":"{read_only:false; response_revision:24365; number_of_response:1; }","duration":"193.098102ms","start":"2026-05-09T02:24:40.04325Z","end":"2026-05-09T02:24:40.236348Z","steps":["trace[1220609737] 'process raft request' (duration: 192.806764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:40.236557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.891798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-09T02:24:40.236675Z","caller":"traceutil/trace.go:171","msg":"trace[1256808967] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24366; }","duration":"229.050642ms","start":"2026-05-09T02:24:40.007602Z","end":"2026-05-09T02:24:40.236653Z","steps":["trace[1256808967] 'agreement among raft nodes before linearized reading' (duration: 228.768314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:40.236704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.403502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-f7c6f5c64-5zf27\" ","response":"range_response_count:1 size:8646"} {"level":"info","ts":"2026-05-09T02:24:40.236803Z","caller":"traceutil/trace.go:171","msg":"trace[360085943] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-f7c6f5c64-5zf27; range_end:; response_count:1; response_revision:24366; }","duration":"189.552016ms","start":"2026-05-09T02:24:40.047226Z","end":"2026-05-09T02:24:40.236778Z","steps":["trace[360085943] 'agreement among raft nodes before linearized reading' (duration: 189.159166ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:40.442847Z","caller":"traceutil/trace.go:171","msg":"trace[1630401173] linearizableReadLoop","detail":"{readStateIndex:25159; appliedIndex:25158; }","duration":"199.378651ms","start":"2026-05-09T02:24:40.243424Z","end":"2026-05-09T02:24:40.442803Z","steps":["trace[1630401173] 'read index received' (duration: 115.016764ms)","trace[1630401173] 'applied index is now lower than readState.Index' (duration: 84.359757ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:40.443275Z","caller":"traceutil/trace.go:171","msg":"trace[253380394] transaction","detail":"{read_only:false; response_revision:24367; number_of_response:1; }","duration":"200.986045ms","start":"2026-05-09T02:24:40.242264Z","end":"2026-05-09T02:24:40.44325Z","steps":["trace[253380394] 'process raft request' (duration: 116.082973ms)","trace[253380394] 'compare' (duration: 84.197822ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:24:40.443702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.248715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18663"} {"level":"info","ts":"2026-05-09T02:24:40.443741Z","caller":"traceutil/trace.go:171","msg":"trace[877719866] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24367; }","duration":"200.346568ms","start":"2026-05-09T02:24:40.243383Z","end":"2026-05-09T02:24:40.44373Z","steps":["trace[877719866] 'agreement among raft nodes before linearized reading' (duration: 200.136892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:40.485372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.060327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:24:40.485548Z","caller":"traceutil/trace.go:171","msg":"trace[1605488785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24368; }","duration":"234.344355ms","start":"2026-05-09T02:24:40.251177Z","end":"2026-05-09T02:24:40.485521Z","steps":["trace[1605488785] 'agreement among raft nodes before linearized reading' (duration: 233.990335ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:40.486173Z","caller":"traceutil/trace.go:171","msg":"trace[262252109] transaction","detail":"{read_only:false; response_revision:24368; number_of_response:1; }","duration":"231.251031ms","start":"2026-05-09T02:24:40.254868Z","end":"2026-05-09T02:24:40.486119Z","steps":["trace[262252109] 'process raft request' (duration: 229.893784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:40.486307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.493232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-n9xwh\" ","response":"range_response_count:1 size:22122"} {"level":"info","ts":"2026-05-09T02:24:40.486397Z","caller":"traceutil/trace.go:171","msg":"trace[365184623] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-n9xwh; range_end:; response_count:1; response_revision:24368; }","duration":"130.648626ms","start":"2026-05-09T02:24:40.355729Z","end":"2026-05-09T02:24:40.486377Z","steps":["trace[365184623] 'agreement among raft nodes before linearized reading' (duration: 130.39443ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:45.897599Z","caller":"traceutil/trace.go:171","msg":"trace[1375298482] linearizableReadLoop","detail":"{readStateIndex:25207; appliedIndex:25206; }","duration":"126.012171ms","start":"2026-05-09T02:24:45.77155Z","end":"2026-05-09T02:24:45.897562Z","steps":["trace[1375298482] 'read index received' (duration: 125.818135ms)","trace[1375298482] 'applied index is now lower than readState.Index' (duration: 192.876µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:45.897626Z","caller":"traceutil/trace.go:171","msg":"trace[1531403050] transaction","detail":"{read_only:false; response_revision:24414; number_of_response:1; }","duration":"182.30193ms","start":"2026-05-09T02:24:45.715295Z","end":"2026-05-09T02:24:45.897597Z","steps":["trace[1531403050] 'process raft request' (duration: 182.069553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:45.897799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.229167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:24:45.897847Z","caller":"traceutil/trace.go:171","msg":"trace[95458113] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24414; }","duration":"126.34872ms","start":"2026-05-09T02:24:45.771483Z","end":"2026-05-09T02:24:45.897832Z","steps":["trace[95458113] 'agreement among raft nodes before linearized reading' (duration: 126.250057ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:46.122544Z","caller":"traceutil/trace.go:171","msg":"trace[1015770782] transaction","detail":"{read_only:false; response_revision:24415; number_of_response:1; }","duration":"182.819763ms","start":"2026-05-09T02:24:45.939692Z","end":"2026-05-09T02:24:46.122512Z","steps":["trace[1015770782] 'process raft request' (duration: 168.9785ms)","trace[1015770782] 'compare' (duration: 12.792445ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:46.122839Z","caller":"traceutil/trace.go:171","msg":"trace[1871408877] linearizableReadLoop","detail":"{readStateIndex:25209; appliedIndex:25207; }","duration":"165.573838ms","start":"2026-05-09T02:24:45.957198Z","end":"2026-05-09T02:24:46.122771Z","steps":["trace[1871408877] 'read index received' (duration: 151.404636ms)","trace[1871408877] 'applied index is now lower than readState.Index' (duration: 14.168142ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:46.122851Z","caller":"traceutil/trace.go:171","msg":"trace[1928547132] transaction","detail":"{read_only:false; response_revision:24416; number_of_response:1; }","duration":"182.158976ms","start":"2026-05-09T02:24:45.940653Z","end":"2026-05-09T02:24:46.122812Z","steps":["trace[1928547132] 'process raft request' (duration: 181.985842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:46.123043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.825015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-09T02:24:46.123212Z","caller":"traceutil/trace.go:171","msg":"trace[786138826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24416; }","duration":"166.038951ms","start":"2026-05-09T02:24:45.957158Z","end":"2026-05-09T02:24:46.123197Z","steps":["trace[786138826] 'agreement among raft nodes before linearized reading' (duration: 165.752933ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:46.533523Z","caller":"traceutil/trace.go:171","msg":"trace[121784529] transaction","detail":"{read_only:false; response_revision:24418; number_of_response:1; }","duration":"320.688945ms","start":"2026-05-09T02:24:46.212781Z","end":"2026-05-09T02:24:46.53347Z","steps":["trace[121784529] 'process raft request' (duration: 320.49611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:46.533717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:46.212701Z","time spent":"320.914371ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:24:46.688422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T02:24:46.6887Z","caller":"traceutil/trace.go:171","msg":"trace[217754409] transaction","detail":"{read_only:false; response_revision:24419; number_of_response:1; }","duration":"457.560209ms","start":"2026-05-09T02:24:46.231112Z","end":"2026-05-09T02:24:46.688673Z","steps":["trace[217754409] 'process raft request' (duration: 336.776659ms)","trace[217754409] 'compare' (duration: 120.118942ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:24:46.688892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:46.231096Z","time spent":"457.690812ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T02:24:46.689271Z","caller":"traceutil/trace.go:171","msg":"trace[105776674] linearizableReadLoop","detail":"{readStateIndex:25212; appliedIndex:25210; }","duration":"442.371049ms","start":"2026-05-09T02:24:46.246883Z","end":"2026-05-09T02:24:46.689254Z","steps":["trace[105776674] 'read index received' (duration: 286.498173ms)","trace[105776674] 'applied index is now lower than readState.Index' (duration: 155.871466ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:46.689301Z","caller":"traceutil/trace.go:171","msg":"trace[1341642080] transaction","detail":"{read_only:false; response_revision:24421; number_of_response:1; }","duration":"429.473831ms","start":"2026-05-09T02:24:46.259795Z","end":"2026-05-09T02:24:46.689269Z","steps":["trace[1341642080] 'process raft request' (duration: 429.353857ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:46.689375Z","caller":"traceutil/trace.go:171","msg":"trace[425849111] transaction","detail":"{read_only:false; response_revision:24422; number_of_response:1; }","duration":"419.751289ms","start":"2026-05-09T02:24:46.269611Z","end":"2026-05-09T02:24:46.689363Z","steps":["trace[425849111] 'process raft request' (duration: 419.596425ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:46.689545Z","caller":"traceutil/trace.go:171","msg":"trace[1429929640] transaction","detail":"{read_only:false; response_revision:24420; number_of_response:1; }","duration":"431.844595ms","start":"2026-05-09T02:24:46.257671Z","end":"2026-05-09T02:24:46.689515Z","steps":["trace[1429929640] 'process raft request' (duration: 430.870739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:46.689649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:46.269587Z","time spent":"420.013615ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:24:46.689677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:46.259759Z","time spent":"429.628155ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:24:46.689695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:46.257647Z","time spent":"431.963039ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:24:46.689831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.942494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:24:46.689896Z","caller":"traceutil/trace.go:171","msg":"trace[829774212] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:24422; }","duration":"443.032537ms","start":"2026-05-09T02:24:46.24685Z","end":"2026-05-09T02:24:46.689882Z","steps":["trace[829774212] 'agreement among raft nodes before linearized reading' (duration: 442.945425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:46.689929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.012683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-rt8f9\" ","response":"range_response_count:1 size:9796"} {"level":"warn","ts":"2026-05-09T02:24:46.689937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:46.246832Z","time spent":"443.093619ms","remote":"127.0.0.1:53092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":29,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true "} {"level":"warn","ts":"2026-05-09T02:24:46.689934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.503119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-09T02:24:46.690008Z","caller":"traceutil/trace.go:171","msg":"trace[295956050] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24422; }","duration":"297.624044ms","start":"2026-05-09T02:24:46.392372Z","end":"2026-05-09T02:24:46.689996Z","steps":["trace[295956050] 'agreement among raft nodes before linearized reading' (duration: 297.433078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:46.690302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.821744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:24:46.690357Z","caller":"traceutil/trace.go:171","msg":"trace[1276001019] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:24422; }","duration":"179.913696ms","start":"2026-05-09T02:24:46.510429Z","end":"2026-05-09T02:24:46.690343Z","steps":["trace[1276001019] 'agreement among raft nodes before linearized reading' (duration: 179.840384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:46.690403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.351111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-09T02:24:46.69045Z","caller":"traceutil/trace.go:171","msg":"trace[1650884233] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24422; }","duration":"222.439034ms","start":"2026-05-09T02:24:46.468Z","end":"2026-05-09T02:24:46.690439Z","steps":["trace[1650884233] 'agreement among raft nodes before linearized reading' (duration: 222.28847ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:46.689969Z","caller":"traceutil/trace.go:171","msg":"trace[941954074] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-rt8f9; range_end:; response_count:1; response_revision:24422; }","duration":"132.050154ms","start":"2026-05-09T02:24:46.557908Z","end":"2026-05-09T02:24:46.689958Z","steps":["trace[941954074] 'agreement among raft nodes before linearized reading' (duration: 131.90153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:46.691512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.38426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-09T02:24:46.691595Z","caller":"traceutil/trace.go:171","msg":"trace[987432750] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:24422; }","duration":"143.532603ms","start":"2026-05-09T02:24:46.548046Z","end":"2026-05-09T02:24:46.691579Z","steps":["trace[987432750] 'agreement among raft nodes before linearized reading' (duration: 142.260139ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:47.061921Z","caller":"traceutil/trace.go:171","msg":"trace[356332393] transaction","detail":"{read_only:false; response_revision:24423; number_of_response:1; }","duration":"358.498416ms","start":"2026-05-09T02:24:46.703392Z","end":"2026-05-09T02:24:47.06189Z","steps":["trace[356332393] 'process raft request' (duration: 358.315931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:47.062095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:46.703371Z","time spent":"358.63832ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T02:24:47.148075Z","caller":"traceutil/trace.go:171","msg":"trace[1887395172] transaction","detail":"{read_only:false; response_revision:24424; number_of_response:1; }","duration":"444.548798ms","start":"2026-05-09T02:24:46.703501Z","end":"2026-05-09T02:24:47.14805Z","steps":["trace[1887395172] 'process raft request' (duration: 442.541003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:47.148232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:46.703451Z","time spent":"444.698003ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T02:24:47.148414Z","caller":"traceutil/trace.go:171","msg":"trace[1765986918] linearizableReadLoop","detail":"{readStateIndex:25217; appliedIndex:25215; }","duration":"327.071877ms","start":"2026-05-09T02:24:46.821293Z","end":"2026-05-09T02:24:47.148364Z","steps":["trace[1765986918] 'read index received' (duration: 240.437269ms)","trace[1765986918] 'applied index is now lower than readState.Index' (duration: 86.632078ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:24:47.148655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.353325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-09T02:24:47.148719Z","caller":"traceutil/trace.go:171","msg":"trace[1963658659] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:24425; }","duration":"327.467738ms","start":"2026-05-09T02:24:46.821237Z","end":"2026-05-09T02:24:47.148704Z","steps":["trace[1963658659] 'agreement among raft nodes before linearized reading' (duration: 327.286364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:47.14876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:46.82121Z","time spent":"327.5423ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/199.204.45.19\" "} {"level":"info","ts":"2026-05-09T02:24:47.148824Z","caller":"traceutil/trace.go:171","msg":"trace[1535078679] transaction","detail":"{read_only:false; response_revision:24425; number_of_response:1; }","duration":"100.603975ms","start":"2026-05-09T02:24:47.047836Z","end":"2026-05-09T02:24:47.14844Z","steps":["trace[1535078679] 'process raft request' (duration: 100.164084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:47.480936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.852206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T02:24:47.481107Z","caller":"traceutil/trace.go:171","msg":"trace[1709204647] transaction","detail":"{read_only:false; response_revision:24426; number_of_response:1; }","duration":"298.98837ms","start":"2026-05-09T02:24:47.182088Z","end":"2026-05-09T02:24:47.481076Z","steps":["trace[1709204647] 'process raft request' (duration: 132.886798ms)","trace[1709204647] 'compare' (duration: 165.727032ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:47.481418Z","caller":"traceutil/trace.go:171","msg":"trace[1449032596] linearizableReadLoop","detail":"{readStateIndex:25220; appliedIndex:25219; }","duration":"229.603537ms","start":"2026-05-09T02:24:47.251783Z","end":"2026-05-09T02:24:47.481386Z","steps":["trace[1449032596] 'read index received' (duration: 63.167436ms)","trace[1449032596] 'applied index is now lower than readState.Index' (duration: 166.432301ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:24:47.481616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.810813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:24:47.48169Z","caller":"traceutil/trace.go:171","msg":"trace[291536331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24427; }","duration":"229.952056ms","start":"2026-05-09T02:24:47.251719Z","end":"2026-05-09T02:24:47.481671Z","steps":["trace[291536331] 'agreement among raft nodes before linearized reading' (duration: 229.801522ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:48.250319Z","caller":"traceutil/trace.go:171","msg":"trace[974137442] transaction","detail":"{read_only:false; response_revision:24429; number_of_response:1; }","duration":"344.165338ms","start":"2026-05-09T02:24:47.906127Z","end":"2026-05-09T02:24:48.250292Z","steps":["trace[974137442] 'process raft request' (duration: 343.987763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:48.25047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:47.906109Z","time spent":"344.283962ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T02:24:48.355123Z","caller":"traceutil/trace.go:171","msg":"trace[897137793] transaction","detail":"{read_only:false; response_revision:24430; number_of_response:1; }","duration":"227.14232ms","start":"2026-05-09T02:24:48.127955Z","end":"2026-05-09T02:24:48.355097Z","steps":["trace[897137793] 'process raft request' (duration: 225.715241ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:48.355316Z","caller":"traceutil/trace.go:171","msg":"trace[369567374] linearizableReadLoop","detail":"{readStateIndex:25225; appliedIndex:25222; }","duration":"165.23675ms","start":"2026-05-09T02:24:48.190024Z","end":"2026-05-09T02:24:48.35526Z","steps":["trace[369567374] 'read index received' (duration: 60.127782ms)","trace[369567374] 'applied index is now lower than readState.Index' (duration: 105.106828ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:48.355353Z","caller":"traceutil/trace.go:171","msg":"trace[955837225] transaction","detail":"{read_only:false; response_revision:24431; number_of_response:1; }","duration":"226.063591ms","start":"2026-05-09T02:24:48.129262Z","end":"2026-05-09T02:24:48.355325Z","steps":["trace[955837225] 'process raft request' (duration: 225.787104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:48.355519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.481476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-09T02:24:48.355568Z","caller":"traceutil/trace.go:171","msg":"trace[1934748889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24431; }","duration":"165.579129ms","start":"2026-05-09T02:24:48.189978Z","end":"2026-05-09T02:24:48.355557Z","steps":["trace[1934748889] 'agreement among raft nodes before linearized reading' (duration: 165.424055ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:48.947912Z","caller":"traceutil/trace.go:171","msg":"trace[2116237745] transaction","detail":"{read_only:false; response_revision:24434; number_of_response:1; }","duration":"252.104963ms","start":"2026-05-09T02:24:48.695788Z","end":"2026-05-09T02:24:48.947893Z","steps":["trace[2116237745] 'process raft request' (duration: 251.9901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:49.201022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.734112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T02:24:49.20131Z","caller":"traceutil/trace.go:171","msg":"trace[569392467] linearizableReadLoop","detail":"{readStateIndex:25230; appliedIndex:25227; }","duration":"483.714245ms","start":"2026-05-09T02:24:48.717574Z","end":"2026-05-09T02:24:49.201289Z","steps":["trace[569392467] 'read index received' (duration: 230.286735ms)","trace[569392467] 'applied index is now lower than readState.Index' (duration: 253.42636ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:49.201415Z","caller":"traceutil/trace.go:171","msg":"trace[910406217] transaction","detail":"{read_only:false; response_revision:24435; number_of_response:1; }","duration":"504.535957ms","start":"2026-05-09T02:24:48.696864Z","end":"2026-05-09T02:24:49.2014Z","steps":["trace[910406217] 'process raft request' (duration: 351.321882ms)","trace[910406217] 'compare' (duration: 152.466645ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:24:49.201532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:48.696856Z","time spent":"504.596838ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T02:24:49.201536Z","caller":"traceutil/trace.go:171","msg":"trace[1015101380] transaction","detail":"{read_only:false; response_revision:24436; number_of_response:1; }","duration":"504.095325ms","start":"2026-05-09T02:24:48.697411Z","end":"2026-05-09T02:24:49.201506Z","steps":["trace[1015101380] 'process raft request' (duration: 503.767646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:49.201694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:48.697404Z","time spent":"504.200507ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:24:49.201733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.143566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-n9xwh\" ","response":"range_response_count:1 size:22122"} {"level":"info","ts":"2026-05-09T02:24:49.201839Z","caller":"traceutil/trace.go:171","msg":"trace[1666163057] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-n9xwh; range_end:; response_count:1; response_revision:24436; }","duration":"484.251209ms","start":"2026-05-09T02:24:48.71756Z","end":"2026-05-09T02:24:49.201811Z","steps":["trace[1666163057] 'agreement among raft nodes before linearized reading' (duration: 484.012173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:49.201882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:48.717495Z","time spent":"484.378723ms","remote":"127.0.0.1:59184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22145,"request content":"key:\"/registry/pods/openstack/nova-compute-default-n9xwh\" "} {"level":"warn","ts":"2026-05-09T02:24:49.204193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.634916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-rt8f9\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-05-09T02:24:49.204248Z","caller":"traceutil/trace.go:171","msg":"trace[900102127] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-rt8f9; range_end:; response_count:1; response_revision:24437; }","duration":"485.708748ms","start":"2026-05-09T02:24:48.718528Z","end":"2026-05-09T02:24:49.204237Z","steps":["trace[900102127] 'agreement among raft nodes before linearized reading' (duration: 485.524974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:49.204283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:48.718521Z","time spent":"485.755319ms","remote":"127.0.0.1:59184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9819,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-rt8f9\" "} {"level":"warn","ts":"2026-05-09T02:24:49.204499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.073015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-09T02:24:49.204534Z","caller":"traceutil/trace.go:171","msg":"trace[1945281970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24437; }","duration":"138.136907ms","start":"2026-05-09T02:24:49.066389Z","end":"2026-05-09T02:24:49.204526Z","steps":["trace[1945281970] 'agreement among raft nodes before linearized reading' (duration: 138.044675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:49.204651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.987534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:24:49.20471Z","caller":"traceutil/trace.go:171","msg":"trace[1665445848] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:24437; }","duration":"194.106678ms","start":"2026-05-09T02:24:49.010588Z","end":"2026-05-09T02:24:49.204695Z","steps":["trace[1665445848] 'agreement among raft nodes before linearized reading' (duration: 194.002155ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:49.705414Z","caller":"traceutil/trace.go:171","msg":"trace[426206199] linearizableReadLoop","detail":"{readStateIndex:25234; appliedIndex:25233; }","duration":"207.816658ms","start":"2026-05-09T02:24:49.497571Z","end":"2026-05-09T02:24:49.705388Z","steps":["trace[426206199] 'read index received' (duration: 207.683995ms)","trace[426206199] 'applied index is now lower than readState.Index' (duration: 131.673µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:49.705468Z","caller":"traceutil/trace.go:171","msg":"trace[1561440115] transaction","detail":"{read_only:false; response_revision:24440; number_of_response:1; }","duration":"215.29273ms","start":"2026-05-09T02:24:49.490137Z","end":"2026-05-09T02:24:49.705429Z","steps":["trace[1561440115] 'process raft request' (duration: 215.101685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:49.705715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.128147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T02:24:49.705778Z","caller":"traceutil/trace.go:171","msg":"trace[858582411] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24440; }","duration":"208.235369ms","start":"2026-05-09T02:24:49.497525Z","end":"2026-05-09T02:24:49.70576Z","steps":["trace[858582411] 'agreement among raft nodes before linearized reading' (duration: 208.006013ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:49.823596Z","caller":"traceutil/trace.go:171","msg":"trace[1616883980] transaction","detail":"{read_only:false; response_revision:24441; number_of_response:1; }","duration":"114.344607ms","start":"2026-05-09T02:24:49.709221Z","end":"2026-05-09T02:24:49.823566Z","steps":["trace[1616883980] 'process raft request' (duration: 93.356791ms)","trace[1616883980] 'compare' (duration: 20.875343ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:50.369107Z","caller":"traceutil/trace.go:171","msg":"trace[1579298476] linearizableReadLoop","detail":"{readStateIndex:25236; appliedIndex:25235; }","duration":"108.601231ms","start":"2026-05-09T02:24:50.260484Z","end":"2026-05-09T02:24:50.369086Z","steps":["trace[1579298476] 'read index received' (duration: 108.56918ms)","trace[1579298476] 'applied index is now lower than readState.Index' (duration: 31.041µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:50.369184Z","caller":"traceutil/trace.go:171","msg":"trace[84389563] transaction","detail":"{read_only:false; response_revision:24442; number_of_response:1; }","duration":"110.39691ms","start":"2026-05-09T02:24:50.258765Z","end":"2026-05-09T02:24:50.369162Z","steps":["trace[84389563] 'process raft request' (duration: 110.189435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:50.369241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.744125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:24:50.369323Z","caller":"traceutil/trace.go:171","msg":"trace[298156095] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:24442; }","duration":"108.875839ms","start":"2026-05-09T02:24:50.260431Z","end":"2026-05-09T02:24:50.369307Z","steps":["trace[298156095] 'agreement among raft nodes before linearized reading' (duration: 108.741265ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:50.556128Z","caller":"traceutil/trace.go:171","msg":"trace[517277429] linearizableReadLoop","detail":"{readStateIndex:25238; appliedIndex:25236; }","duration":"168.036545ms","start":"2026-05-09T02:24:50.388065Z","end":"2026-05-09T02:24:50.556102Z","steps":["trace[517277429] 'read index received' (duration: 107.432499ms)","trace[517277429] 'applied index is now lower than readState.Index' (duration: 60.602956ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:50.556264Z","caller":"traceutil/trace.go:171","msg":"trace[1904121499] transaction","detail":"{read_only:false; response_revision:24444; number_of_response:1; }","duration":"195.579489ms","start":"2026-05-09T02:24:50.360669Z","end":"2026-05-09T02:24:50.556249Z","steps":["trace[1904121499] 'process raft request' (duration: 195.333462ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:50.556201Z","caller":"traceutil/trace.go:171","msg":"trace[1058597862] transaction","detail":"{read_only:false; response_revision:24443; number_of_response:1; }","duration":"195.672561ms","start":"2026-05-09T02:24:50.360466Z","end":"2026-05-09T02:24:50.556138Z","steps":["trace[1058597862] 'process raft request' (duration: 135.238479ms)","trace[1058597862] 'compare' (duration: 60.010769ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:24:50.556494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.409886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-09T02:24:50.556572Z","caller":"traceutil/trace.go:171","msg":"trace[1910118942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24444; }","duration":"168.495928ms","start":"2026-05-09T02:24:50.388059Z","end":"2026-05-09T02:24:50.556555Z","steps":["trace[1910118942] 'agreement among raft nodes before linearized reading' (duration: 168.361534ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:50.817653Z","caller":"traceutil/trace.go:171","msg":"trace[704884635] transaction","detail":"{read_only:false; response_revision:24445; number_of_response:1; }","duration":"256.972596ms","start":"2026-05-09T02:24:50.560657Z","end":"2026-05-09T02:24:50.81763Z","steps":["trace[704884635] 'process raft request' (duration: 201.807497ms)","trace[704884635] 'compare' (duration: 54.989564ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:50.878535Z","caller":"traceutil/trace.go:171","msg":"trace[211116578] linearizableReadLoop","detail":"{readStateIndex:25240; appliedIndex:25238; }","duration":"283.144011ms","start":"2026-05-09T02:24:50.595373Z","end":"2026-05-09T02:24:50.878517Z","steps":["trace[211116578] 'read index received' (duration: 167.367746ms)","trace[211116578] 'applied index is now lower than readState.Index' (duration: 115.775285ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:50.87859Z","caller":"traceutil/trace.go:171","msg":"trace[805745471] transaction","detail":"{read_only:false; response_revision:24446; number_of_response:1; }","duration":"317.223932ms","start":"2026-05-09T02:24:50.561343Z","end":"2026-05-09T02:24:50.878567Z","steps":["trace[805745471] 'process raft request' (duration: 317.082978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:50.878727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.339617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:24:50.878784Z","caller":"traceutil/trace.go:171","msg":"trace[400725580] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:24446; }","duration":"283.46008ms","start":"2026-05-09T02:24:50.595313Z","end":"2026-05-09T02:24:50.878774Z","steps":["trace[400725580] 'agreement among raft nodes before linearized reading' (duration: 283.355987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:50.878718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:50.561318Z","time spent":"317.321635ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:24:50.878897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.363137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-09T02:24:50.878931Z","caller":"traceutil/trace.go:171","msg":"trace[74540074] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24446; }","duration":"260.436099ms","start":"2026-05-09T02:24:50.618485Z","end":"2026-05-09T02:24:50.878921Z","steps":["trace[74540074] 'agreement among raft nodes before linearized reading' (duration: 260.289245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:51.049524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.349438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:24:51.049779Z","caller":"traceutil/trace.go:171","msg":"trace[1415664966] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24446; }","duration":"107.506322ms","start":"2026-05-09T02:24:50.942085Z","end":"2026-05-09T02:24:51.049591Z","steps":["trace[1415664966] 'range keys from in-memory index tree' (duration: 107.239635ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:51.443229Z","caller":"traceutil/trace.go:171","msg":"trace[1515184644] linearizableReadLoop","detail":"{readStateIndex:25245; appliedIndex:25244; }","duration":"153.162623ms","start":"2026-05-09T02:24:51.290042Z","end":"2026-05-09T02:24:51.443205Z","steps":["trace[1515184644] 'read index received' (duration: 153.04196ms)","trace[1515184644] 'applied index is now lower than readState.Index' (duration: 119.293µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:24:51.443357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.853612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:24:51.443392Z","caller":"traceutil/trace.go:171","msg":"trace[811934686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24451; }","duration":"194.002506ms","start":"2026-05-09T02:24:51.249376Z","end":"2026-05-09T02:24:51.443378Z","steps":["trace[811934686] 'agreement among raft nodes before linearized reading' (duration: 193.926784ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:51.443418Z","caller":"traceutil/trace.go:171","msg":"trace[765881668] transaction","detail":"{read_only:false; response_revision:24451; number_of_response:1; }","duration":"217.415548ms","start":"2026-05-09T02:24:51.225957Z","end":"2026-05-09T02:24:51.443372Z","steps":["trace[765881668] 'process raft request' (duration: 217.075059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:51.443449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.702843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-rt8f9\" ","response":"range_response_count:1 size:9796"} {"level":"warn","ts":"2026-05-09T02:24:51.443491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.277695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-09T02:24:51.443523Z","caller":"traceutil/trace.go:171","msg":"trace[1457455218] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-rt8f9; range_end:; response_count:1; response_revision:24451; }","duration":"168.832986ms","start":"2026-05-09T02:24:51.274672Z","end":"2026-05-09T02:24:51.443505Z","steps":["trace[1457455218] 'agreement among raft nodes before linearized reading' (duration: 168.567329ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:51.443564Z","caller":"traceutil/trace.go:171","msg":"trace[805974681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24451; }","duration":"190.40599ms","start":"2026-05-09T02:24:51.253142Z","end":"2026-05-09T02:24:51.443548Z","steps":["trace[805974681] 'agreement among raft nodes before linearized reading' (duration: 190.252155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:51.444465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.989012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-n9xwh\" ","response":"range_response_count:1 size:22122"} {"level":"info","ts":"2026-05-09T02:24:51.444555Z","caller":"traceutil/trace.go:171","msg":"trace[844005891] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-n9xwh; range_end:; response_count:1; response_revision:24451; }","duration":"172.111836ms","start":"2026-05-09T02:24:51.272416Z","end":"2026-05-09T02:24:51.444528Z","steps":["trace[844005891] 'agreement among raft nodes before linearized reading' (duration: 170.861471ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:51.71282Z","caller":"traceutil/trace.go:171","msg":"trace[1524693552] transaction","detail":"{read_only:false; response_revision:24453; number_of_response:1; }","duration":"265.346321ms","start":"2026-05-09T02:24:51.447453Z","end":"2026-05-09T02:24:51.712799Z","steps":["trace[1524693552] 'process raft request' (duration: 265.260709ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:51.713061Z","caller":"traceutil/trace.go:171","msg":"trace[1734604472] linearizableReadLoop","detail":"{readStateIndex:25246; appliedIndex:25245; }","duration":"268.666752ms","start":"2026-05-09T02:24:51.444372Z","end":"2026-05-09T02:24:51.713039Z","steps":["trace[1734604472] 'read index received' (duration: 240.344856ms)","trace[1734604472] 'applied index is now lower than readState.Index' (duration: 28.320806ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:51.713087Z","caller":"traceutil/trace.go:171","msg":"trace[1128552723] transaction","detail":"{read_only:false; response_revision:24452; number_of_response:1; }","duration":"418.981298ms","start":"2026-05-09T02:24:51.294081Z","end":"2026-05-09T02:24:51.713063Z","steps":["trace[1128552723] 'process raft request' (duration: 390.621652ms)","trace[1128552723] 'compare' (duration: 27.600435ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:24:51.713203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:51.294038Z","time spent":"419.091861ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:24:51.713248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.826446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:24:51.71341Z","caller":"traceutil/trace.go:171","msg":"trace[307051517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24453; }","duration":"269.069042ms","start":"2026-05-09T02:24:51.444321Z","end":"2026-05-09T02:24:51.71339Z","steps":["trace[307051517] 'agreement among raft nodes before linearized reading' (duration: 268.812245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:51.927138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.619187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T02:24:51.927449Z","caller":"traceutil/trace.go:171","msg":"trace[804733219] transaction","detail":"{read_only:false; response_revision:24454; number_of_response:1; }","duration":"229.606197ms","start":"2026-05-09T02:24:51.697798Z","end":"2026-05-09T02:24:51.927404Z","steps":["trace[804733219] 'process raft request' (duration: 125.524078ms)","trace[804733219] 'compare' (duration: 103.464742ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:51.927699Z","caller":"traceutil/trace.go:171","msg":"trace[252634161] linearizableReadLoop","detail":"{readStateIndex:25249; appliedIndex:25247; }","duration":"214.55667ms","start":"2026-05-09T02:24:51.71312Z","end":"2026-05-09T02:24:51.927677Z","steps":["trace[252634161] 'read index received' (duration: 110.188473ms)","trace[252634161] 'applied index is now lower than readState.Index' (duration: 104.367117ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:51.927781Z","caller":"traceutil/trace.go:171","msg":"trace[1206496008] transaction","detail":"{read_only:false; response_revision:24455; number_of_response:1; }","duration":"216.597236ms","start":"2026-05-09T02:24:51.711154Z","end":"2026-05-09T02:24:51.927752Z","steps":["trace[1206496008] 'process raft request' (duration: 216.175635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:51.927882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.021509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T02:24:51.928271Z","caller":"traceutil/trace.go:171","msg":"trace[1844623134] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24455; }","duration":"100.45069ms","start":"2026-05-09T02:24:51.827805Z","end":"2026-05-09T02:24:51.928255Z","steps":["trace[1844623134] 'agreement among raft nodes before linearized reading' (duration: 100.004638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:51.929043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.104405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:134 size:1300860"} {"level":"info","ts":"2026-05-09T02:24:51.929087Z","caller":"traceutil/trace.go:171","msg":"trace[1405168413] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:134; response_revision:24455; }","duration":"481.165156ms","start":"2026-05-09T02:24:51.447913Z","end":"2026-05-09T02:24:51.929078Z","steps":["trace[1405168413] 'agreement among raft nodes before linearized reading' (duration: 479.885821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:51.929114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:51.447891Z","time spent":"481.214178ms","remote":"127.0.0.1:59184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":134,"response size":1300883,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-05-09T02:24:51.929375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.185543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:134 size:1300860"} {"level":"info","ts":"2026-05-09T02:24:51.92969Z","caller":"traceutil/trace.go:171","msg":"trace[700417194] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:134; response_revision:24455; }","duration":"479.528182ms","start":"2026-05-09T02:24:51.45013Z","end":"2026-05-09T02:24:51.929659Z","steps":["trace[700417194] 'agreement among raft nodes before linearized reading' (duration: 477.720693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:51.929749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:51.450109Z","time spent":"479.622965ms","remote":"127.0.0.1:59184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":134,"response size":1300883,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-05-09T02:24:56.098363Z","caller":"traceutil/trace.go:171","msg":"trace[404355880] linearizableReadLoop","detail":"{readStateIndex:25278; appliedIndex:25277; }","duration":"155.554168ms","start":"2026-05-09T02:24:55.94279Z","end":"2026-05-09T02:24:56.098344Z","steps":["trace[404355880] 'read index received' (duration: 155.360753ms)","trace[404355880] 'applied index is now lower than readState.Index' (duration: 192.415µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:56.098413Z","caller":"traceutil/trace.go:171","msg":"trace[50754] transaction","detail":"{read_only:false; response_revision:24483; number_of_response:1; }","duration":"158.58931ms","start":"2026-05-09T02:24:55.939796Z","end":"2026-05-09T02:24:56.098385Z","steps":["trace[50754] 'process raft request' (duration: 158.405445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:56.09852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.712093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:24:56.098604Z","caller":"traceutil/trace.go:171","msg":"trace[1191863149] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24483; }","duration":"155.826526ms","start":"2026-05-09T02:24:55.942763Z","end":"2026-05-09T02:24:56.09859Z","steps":["trace[1191863149] 'agreement among raft nodes before linearized reading' (duration: 155.708813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:56.098692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.643361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-rt8f9\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-05-09T02:24:56.098745Z","caller":"traceutil/trace.go:171","msg":"trace[515054052] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-rt8f9; range_end:; response_count:1; response_revision:24483; }","duration":"105.713374ms","start":"2026-05-09T02:24:55.993019Z","end":"2026-05-09T02:24:56.098732Z","steps":["trace[515054052] 'agreement among raft nodes before linearized reading' (duration: 105.56182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:56.098723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.525652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T02:24:56.098862Z","caller":"traceutil/trace.go:171","msg":"trace[1377255175] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24483; }","duration":"147.659726ms","start":"2026-05-09T02:24:55.951157Z","end":"2026-05-09T02:24:56.098816Z","steps":["trace[1377255175] 'agreement among raft nodes before linearized reading' (duration: 147.344787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:56.099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.158945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-n9xwh\" ","response":"range_response_count:1 size:22122"} {"level":"info","ts":"2026-05-09T02:24:56.099043Z","caller":"traceutil/trace.go:171","msg":"trace[1438561928] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-n9xwh; range_end:; response_count:1; response_revision:24483; }","duration":"106.247258ms","start":"2026-05-09T02:24:55.992786Z","end":"2026-05-09T02:24:56.099033Z","steps":["trace[1438561928] 'agreement among raft nodes before linearized reading' (duration: 106.130305ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:56.754418Z","caller":"traceutil/trace.go:171","msg":"trace[503872581] linearizableReadLoop","detail":"{readStateIndex:25280; appliedIndex:25279; }","duration":"281.61121ms","start":"2026-05-09T02:24:56.472785Z","end":"2026-05-09T02:24:56.754396Z","steps":["trace[503872581] 'read index received' (duration: 281.589379ms)","trace[503872581] 'applied index is now lower than readState.Index' (duration: 20.86µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:56.754503Z","caller":"traceutil/trace.go:171","msg":"trace[317364013] transaction","detail":"{read_only:false; response_revision:24485; number_of_response:1; }","duration":"300.265424ms","start":"2026-05-09T02:24:56.454216Z","end":"2026-05-09T02:24:56.754482Z","steps":["trace[317364013] 'process raft request' (duration: 300.043298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:56.75456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.747864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:24:56.754599Z","caller":"traceutil/trace.go:171","msg":"trace[339240260] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:24485; }","duration":"281.830766ms","start":"2026-05-09T02:24:56.472757Z","end":"2026-05-09T02:24:56.754587Z","steps":["trace[339240260] 'agreement among raft nodes before linearized reading' (duration: 281.746753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:56.754613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:56.454191Z","time spent":"300.360607ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T02:24:56.948721Z","caller":"traceutil/trace.go:171","msg":"trace[1581735578] linearizableReadLoop","detail":"{readStateIndex:25282; appliedIndex:25280; }","duration":"194.221352ms","start":"2026-05-09T02:24:56.754476Z","end":"2026-05-09T02:24:56.948697Z","steps":["trace[1581735578] 'read index received' (duration: 180.146171ms)","trace[1581735578] 'applied index is now lower than readState.Index' (duration: 14.074171ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:56.948776Z","caller":"traceutil/trace.go:171","msg":"trace[1462248738] transaction","detail":"{read_only:false; response_revision:24487; number_of_response:1; }","duration":"369.591885ms","start":"2026-05-09T02:24:56.579161Z","end":"2026-05-09T02:24:56.948753Z","steps":["trace[1462248738] 'process raft request' (duration: 369.382748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:56.948887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:56.579144Z","time spent":"369.681337ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:24:56.948888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.420169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:24:56.948898Z","caller":"traceutil/trace.go:171","msg":"trace[1114563331] transaction","detail":"{read_only:false; response_revision:24486; number_of_response:1; }","duration":"371.99756ms","start":"2026-05-09T02:24:56.576876Z","end":"2026-05-09T02:24:56.948873Z","steps":["trace[1114563331] 'process raft request' (duration: 357.733004ms)","trace[1114563331] 'compare' (duration: 13.491394ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:24:56.949032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:56.576852Z","time spent":"372.105713ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:24:56.94906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.278409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-09T02:24:56.94909Z","caller":"traceutil/trace.go:171","msg":"trace[86048197] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:24487; }","duration":"471.330131ms","start":"2026-05-09T02:24:56.477752Z","end":"2026-05-09T02:24:56.949082Z","steps":["trace[86048197] 'agreement among raft nodes before linearized reading' (duration: 471.198138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:56.949123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:56.477729Z","time spent":"471.386623ms","remote":"127.0.0.1:59164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" "} {"level":"info","ts":"2026-05-09T02:24:56.948929Z","caller":"traceutil/trace.go:171","msg":"trace[362390258] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24487; }","duration":"343.522812ms","start":"2026-05-09T02:24:56.605391Z","end":"2026-05-09T02:24:56.948914Z","steps":["trace[362390258] 'agreement among raft nodes before linearized reading' (duration: 343.418239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:56.949205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:56.605374Z","time spent":"343.819409ms","remote":"127.0.0.1:41278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"info","ts":"2026-05-09T02:24:57.254223Z","caller":"traceutil/trace.go:171","msg":"trace[1277348165] linearizableReadLoop","detail":"{readStateIndex:25283; appliedIndex:25282; }","duration":"305.420344ms","start":"2026-05-09T02:24:56.948784Z","end":"2026-05-09T02:24:57.254205Z","steps":["trace[1277348165] 'read index received' (duration: 290.211273ms)","trace[1277348165] 'applied index is now lower than readState.Index' (duration: 15.207771ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:24:57.254339Z","caller":"traceutil/trace.go:171","msg":"trace[1695353239] transaction","detail":"{read_only:false; response_revision:24488; number_of_response:1; }","duration":"322.561477ms","start":"2026-05-09T02:24:56.931764Z","end":"2026-05-09T02:24:57.254325Z","steps":["trace[1695353239] 'process raft request' (duration: 307.203152ms)","trace[1695353239] 'compare' (duration: 14.862651ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:24:57.254443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:56.931738Z","time spent":"322.634948ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:24:57.254629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.028727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-09T02:24:57.25468Z","caller":"traceutil/trace.go:171","msg":"trace[2056331475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24490; }","duration":"350.090629ms","start":"2026-05-09T02:24:56.904578Z","end":"2026-05-09T02:24:57.254669Z","steps":["trace[2056331475] 'agreement among raft nodes before linearized reading' (duration: 349.953855ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:57.254658Z","caller":"traceutil/trace.go:171","msg":"trace[1106942245] transaction","detail":"{read_only:false; response_revision:24489; number_of_response:1; }","duration":"182.913268ms","start":"2026-05-09T02:24:57.071704Z","end":"2026-05-09T02:24:57.254618Z","steps":["trace[1106942245] 'process raft request' (duration: 182.369533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:57.254719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:56.904572Z","time spent":"350.14094ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T02:24:57.254721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.187426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-7c728c20-e95b-418c-b398-ea6c3f6e86c9\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-09T02:24:57.254762Z","caller":"traceutil/trace.go:171","msg":"trace[863291419] range","detail":"{range_begin:/registry/persistentvolumes/pvc-7c728c20-e95b-418c-b398-ea6c3f6e86c9; range_end:; response_count:1; response_revision:24490; }","duration":"302.247278ms","start":"2026-05-09T02:24:56.952499Z","end":"2026-05-09T02:24:57.254746Z","steps":["trace[863291419] 'agreement among raft nodes before linearized reading' (duration: 302.135735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:57.254787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:56.952361Z","time spent":"302.419122ms","remote":"127.0.0.1:59152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-7c728c20-e95b-418c-b398-ea6c3f6e86c9\" "} {"level":"warn","ts":"2026-05-09T02:24:57.254797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.937515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-09T02:24:57.254795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.811341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-09T02:24:57.25487Z","caller":"traceutil/trace.go:171","msg":"trace[1691665155] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:24490; }","duration":"432.934615ms","start":"2026-05-09T02:24:56.821917Z","end":"2026-05-09T02:24:57.254851Z","steps":["trace[1691665155] 'agreement among raft nodes before linearized reading' (duration: 432.659927ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:24:57.254858Z","caller":"traceutil/trace.go:171","msg":"trace[2017608075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24490; }","duration":"410.034548ms","start":"2026-05-09T02:24:56.844807Z","end":"2026-05-09T02:24:57.254842Z","steps":["trace[2017608075] 'agreement among raft nodes before linearized reading' (duration: 409.797151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:24:57.254939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:56.8219Z","time spent":"433.029628ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/199.204.45.19\" "} {"level":"warn","ts":"2026-05-09T02:24:57.254984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:24:56.844791Z","time spent":"410.175401ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T02:25:02.279532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.58405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T02:25:02.279669Z","caller":"traceutil/trace.go:171","msg":"trace[1820290264] linearizableReadLoop","detail":"{readStateIndex:25400; appliedIndex:25399; }","duration":"150.264495ms","start":"2026-05-09T02:25:02.129385Z","end":"2026-05-09T02:25:02.279649Z","steps":["trace[1820290264] 'read index received' (duration: 41.349756ms)","trace[1820290264] 'applied index is now lower than readState.Index' (duration: 108.913489ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:25:02.279759Z","caller":"traceutil/trace.go:171","msg":"trace[1882479920] transaction","detail":"{read_only:false; response_revision:24602; number_of_response:1; }","duration":"154.323116ms","start":"2026-05-09T02:25:02.125416Z","end":"2026-05-09T02:25:02.279739Z","steps":["trace[1882479920] 'process raft request' (duration: 45.431487ms)","trace[1882479920] 'compare' (duration: 108.163089ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:25:02.280189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.79298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4caa49ec-1cdd-4c13-a034-1cb6d03c6298\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-05-09T02:25:02.280255Z","caller":"traceutil/trace.go:171","msg":"trace[1521931540] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4caa49ec-1cdd-4c13-a034-1cb6d03c6298; range_end:; response_count:1; response_revision:24602; }","duration":"150.887823ms","start":"2026-05-09T02:25:02.129349Z","end":"2026-05-09T02:25:02.280236Z","steps":["trace[1521931540] 'agreement among raft nodes before linearized reading' (duration: 150.683627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:02.281213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.897423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-rt8f9\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-05-09T02:25:02.281278Z","caller":"traceutil/trace.go:171","msg":"trace[250195706] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-rt8f9; range_end:; response_count:1; response_revision:24603; }","duration":"101.013246ms","start":"2026-05-09T02:25:02.180249Z","end":"2026-05-09T02:25:02.281262Z","steps":["trace[250195706] 'agreement among raft nodes before linearized reading' (duration: 100.833801ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:02.48104Z","caller":"traceutil/trace.go:171","msg":"trace[360566443] linearizableReadLoop","detail":"{readStateIndex:25402; appliedIndex:25401; }","duration":"195.134606ms","start":"2026-05-09T02:25:02.285882Z","end":"2026-05-09T02:25:02.481017Z","steps":["trace[360566443] 'read index received' (duration: 194.973602ms)","trace[360566443] 'applied index is now lower than readState.Index' (duration: 159.884µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:25:02.481124Z","caller":"traceutil/trace.go:171","msg":"trace[819648991] transaction","detail":"{read_only:false; response_revision:24604; number_of_response:1; }","duration":"195.491635ms","start":"2026-05-09T02:25:02.285611Z","end":"2026-05-09T02:25:02.481103Z","steps":["trace[819648991] 'process raft request' (duration: 195.255709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:02.482185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.284217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:136 size:1317210"} {"level":"info","ts":"2026-05-09T02:25:02.482249Z","caller":"traceutil/trace.go:171","msg":"trace[1563822966] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:136; response_revision:24604; }","duration":"196.403991ms","start":"2026-05-09T02:25:02.285833Z","end":"2026-05-09T02:25:02.482237Z","steps":["trace[1563822966] 'agreement among raft nodes before linearized reading' (duration: 195.329192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:02.482557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.867116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:136 size:1317210"} {"level":"info","ts":"2026-05-09T02:25:02.482639Z","caller":"traceutil/trace.go:171","msg":"trace[797635789] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:136; response_revision:24604; }","duration":"195.98819ms","start":"2026-05-09T02:25:02.28663Z","end":"2026-05-09T02:25:02.482619Z","steps":["trace[797635789] 'agreement among raft nodes before linearized reading' (duration: 194.653683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:02.820559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.408844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T02:25:02.820929Z","caller":"traceutil/trace.go:171","msg":"trace[1734072426] transaction","detail":"{read_only:false; response_revision:24605; number_of_response:1; }","duration":"332.70849ms","start":"2026-05-09T02:25:02.48817Z","end":"2026-05-09T02:25:02.820878Z","steps":["trace[1734072426] 'process raft request' (duration: 136.847764ms)","trace[1734072426] 'compare' (duration: 194.93159ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:25:02.821194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:02.488135Z","time spent":"332.935616ms","remote":"127.0.0.1:59184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3742,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:25:02.964527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.856972ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-09T02:25:02.96464Z","caller":"traceutil/trace.go:171","msg":"trace[819696814] linearizableReadLoop","detail":"{readStateIndex:25404; appliedIndex:25402; }","duration":"250.009757ms","start":"2026-05-09T02:25:02.714609Z","end":"2026-05-09T02:25:02.964619Z","steps":["trace[819696814] 'read index received' (duration: 51.011µs)","trace[819696814] 'applied index is now lower than readState.Index' (duration: 249.957156ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:25:02.964777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.160911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T02:25:02.964853Z","caller":"traceutil/trace.go:171","msg":"trace[906552334] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:24605; }","duration":"250.244593ms","start":"2026-05-09T02:25:02.714591Z","end":"2026-05-09T02:25:02.964836Z","steps":["trace[906552334] 'agreement among raft nodes before linearized reading' (duration: 250.076339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:02.964919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.891815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T02:25:02.964991Z","caller":"traceutil/trace.go:171","msg":"trace[1657620369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24605; }","duration":"186.997498ms","start":"2026-05-09T02:25:02.777976Z","end":"2026-05-09T02:25:02.964973Z","steps":["trace[1657620369] 'agreement among raft nodes before linearized reading' (duration: 186.756431ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:03.077739Z","caller":"traceutil/trace.go:171","msg":"trace[251457796] transaction","detail":"{read_only:false; response_revision:24608; number_of_response:1; }","duration":"107.48296ms","start":"2026-05-09T02:25:02.970226Z","end":"2026-05-09T02:25:03.077709Z","steps":["trace[251457796] 'process raft request' (duration: 107.412958ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:03.077827Z","caller":"traceutil/trace.go:171","msg":"trace[109110070] transaction","detail":"{read_only:false; response_revision:24607; number_of_response:1; }","duration":"108.922809ms","start":"2026-05-09T02:25:02.968866Z","end":"2026-05-09T02:25:03.077788Z","steps":["trace[109110070] 'process raft request' (duration: 107.07458ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:07.341924Z","caller":"traceutil/trace.go:171","msg":"trace[785741998] transaction","detail":"{read_only:false; response_revision:24652; number_of_response:1; }","duration":"272.574797ms","start":"2026-05-09T02:25:07.069325Z","end":"2026-05-09T02:25:07.3419Z","steps":["trace[785741998] 'process raft request' (duration: 272.372901ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:07.452446Z","caller":"traceutil/trace.go:171","msg":"trace[988579523] transaction","detail":"{read_only:false; response_revision:24653; number_of_response:1; }","duration":"364.375584ms","start":"2026-05-09T02:25:07.088047Z","end":"2026-05-09T02:25:07.452422Z","steps":["trace[988579523] 'process raft request' (duration: 364.104617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:07.452576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:07.088021Z","time spent":"364.487467ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T02:25:07.452445Z","caller":"traceutil/trace.go:171","msg":"trace[49200304] transaction","detail":"{read_only:false; response_revision:24654; number_of_response:1; }","duration":"346.368528ms","start":"2026-05-09T02:25:07.106055Z","end":"2026-05-09T02:25:07.452424Z","steps":["trace[49200304] 'process raft request' (duration: 346.258285ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:07.452829Z","caller":"traceutil/trace.go:171","msg":"trace[447768679] transaction","detail":"{read_only:false; response_revision:24655; number_of_response:1; }","duration":"166.490503ms","start":"2026-05-09T02:25:07.28633Z","end":"2026-05-09T02:25:07.452821Z","steps":["trace[447768679] 'process raft request' (duration: 166.049651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:07.452814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:07.106032Z","time spent":"346.694207ms","remote":"127.0.0.1:59184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9172,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T02:25:07.452863Z","caller":"traceutil/trace.go:171","msg":"trace[1316049052] transaction","detail":"{read_only:false; response_revision:24656; number_of_response:1; }","duration":"158.166058ms","start":"2026-05-09T02:25:07.294673Z","end":"2026-05-09T02:25:07.452839Z","steps":["trace[1316049052] 'process raft request' (duration: 157.989094ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:07.452962Z","caller":"traceutil/trace.go:171","msg":"trace[1713165678] transaction","detail":"{read_only:false; response_revision:24657; number_of_response:1; }","duration":"156.482623ms","start":"2026-05-09T02:25:07.296471Z","end":"2026-05-09T02:25:07.452954Z","steps":["trace[1713165678] 'process raft request' (duration: 156.321499ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:07.453082Z","caller":"traceutil/trace.go:171","msg":"trace[99426133] linearizableReadLoop","detail":"{readStateIndex:25454; appliedIndex:25452; }","duration":"356.884471ms","start":"2026-05-09T02:25:07.096191Z","end":"2026-05-09T02:25:07.453075Z","steps":["trace[99426133] 'read index received' (duration: 245.526786ms)","trace[99426133] 'applied index is now lower than readState.Index' (duration: 111.356875ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:25:07.453148Z","caller":"traceutil/trace.go:171","msg":"trace[611433097] transaction","detail":"{read_only:false; response_revision:24658; number_of_response:1; }","duration":"111.863719ms","start":"2026-05-09T02:25:07.341279Z","end":"2026-05-09T02:25:07.453142Z","steps":["trace[611433097] 'process raft request' (duration: 111.631143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:07.453334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.135408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T02:25:07.453376Z","caller":"traceutil/trace.go:171","msg":"trace[255602448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24658; }","duration":"357.20926ms","start":"2026-05-09T02:25:07.096155Z","end":"2026-05-09T02:25:07.453365Z","steps":["trace[255602448] 'agreement among raft nodes before linearized reading' (duration: 357.066356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:07.453409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:07.096141Z","time spent":"357.260191ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T02:25:07.453731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.021479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:25:07.453814Z","caller":"traceutil/trace.go:171","msg":"trace[1278886787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24659; }","duration":"203.113032ms","start":"2026-05-09T02:25:07.250679Z","end":"2026-05-09T02:25:07.453793Z","steps":["trace[1278886787] 'agreement among raft nodes before linearized reading' (duration: 202.954847ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:07.453909Z","caller":"traceutil/trace.go:171","msg":"trace[1780328242] transaction","detail":"{read_only:false; response_revision:24659; number_of_response:1; }","duration":"109.949518ms","start":"2026-05-09T02:25:07.343947Z","end":"2026-05-09T02:25:07.453896Z","steps":["trace[1780328242] 'process raft request' (duration: 109.556407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:07.453908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.700225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-09T02:25:07.453961Z","caller":"traceutil/trace.go:171","msg":"trace[1771842353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24659; }","duration":"151.827039ms","start":"2026-05-09T02:25:07.302118Z","end":"2026-05-09T02:25:07.453945Z","steps":["trace[1771842353] 'agreement among raft nodes before linearized reading' (duration: 151.657883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:07.454144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.089122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-09T02:25:07.454176Z","caller":"traceutil/trace.go:171","msg":"trace[495564720] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24659; }","duration":"153.158044ms","start":"2026-05-09T02:25:07.301008Z","end":"2026-05-09T02:25:07.454166Z","steps":["trace[495564720] 'agreement among raft nodes before linearized reading' (duration: 153.029211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:08.72121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.993505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-rt8f9\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-05-09T02:25:08.72131Z","caller":"traceutil/trace.go:171","msg":"trace[632054450] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-rt8f9; range_end:; response_count:1; response_revision:24674; }","duration":"141.136549ms","start":"2026-05-09T02:25:08.580149Z","end":"2026-05-09T02:25:08.721286Z","steps":["trace[632054450] 'range keys from in-memory index tree' (duration: 140.799639ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:08.721428Z","caller":"traceutil/trace.go:171","msg":"trace[173441071] linearizableReadLoop","detail":"{readStateIndex:25476; appliedIndex:25475; }","duration":"127.824461ms","start":"2026-05-09T02:25:08.593566Z","end":"2026-05-09T02:25:08.721391Z","steps":["trace[173441071] 'read index received' (duration: 109.341431ms)","trace[173441071] 'applied index is now lower than readState.Index' (duration: 18.48163ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:25:08.721678Z","caller":"traceutil/trace.go:171","msg":"trace[1851930809] transaction","detail":"{read_only:false; response_revision:24675; number_of_response:1; }","duration":"130.552714ms","start":"2026-05-09T02:25:08.591044Z","end":"2026-05-09T02:25:08.721597Z","steps":["trace[1851930809] 'process raft request' (duration: 111.808378ms)","trace[1851930809] 'compare' (duration: 18.089369ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:25:08.721817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.255272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10358"} {"level":"info","ts":"2026-05-09T02:25:08.721871Z","caller":"traceutil/trace.go:171","msg":"trace[2082826075] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24675; }","duration":"128.388505ms","start":"2026-05-09T02:25:08.593471Z","end":"2026-05-09T02:25:08.721859Z","steps":["trace[2082826075] 'agreement among raft nodes before linearized reading' (duration: 128.239571ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:34.501642Z","caller":"traceutil/trace.go:171","msg":"trace[1111846297] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"109.600958ms","start":"2026-05-09T02:25:34.392023Z","end":"2026-05-09T02:25:34.501624Z","steps":["trace[1111846297] 'process raft request' (duration: 109.450334ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:37.735998Z","caller":"traceutil/trace.go:171","msg":"trace[1289334509] transaction","detail":"{read_only:false; response_revision:24916; number_of_response:1; }","duration":"287.17284ms","start":"2026-05-09T02:25:37.448801Z","end":"2026-05-09T02:25:37.735974Z","steps":["trace[1289334509] 'process raft request' (duration: 287.039797ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:38.019412Z","caller":"traceutil/trace.go:171","msg":"trace[385308643] transaction","detail":"{read_only:false; response_revision:24919; number_of_response:1; }","duration":"468.258658ms","start":"2026-05-09T02:25:37.551116Z","end":"2026-05-09T02:25:38.019374Z","steps":["trace[385308643] 'process raft request' (duration: 468.132294ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:38.019502Z","caller":"traceutil/trace.go:171","msg":"trace[1392657632] transaction","detail":"{read_only:false; response_revision:24917; number_of_response:1; }","duration":"525.04411ms","start":"2026-05-09T02:25:37.494402Z","end":"2026-05-09T02:25:38.019447Z","steps":["trace[1392657632] 'process raft request' (duration: 438.492414ms)","trace[1392657632] 'compare' (duration: 85.910889ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:25:38.019643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:37.551101Z","time spent":"468.412241ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:25:38.019667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:37.49438Z","time spent":"525.191434ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T02:25:38.019659Z","caller":"traceutil/trace.go:171","msg":"trace[674137399] transaction","detail":"{read_only:false; response_revision:24918; number_of_response:1; }","duration":"469.036548ms","start":"2026-05-09T02:25:37.550587Z","end":"2026-05-09T02:25:38.019624Z","steps":["trace[674137399] 'process raft request' (duration: 468.564306ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:38.019799Z","caller":"traceutil/trace.go:171","msg":"trace[1894114719] transaction","detail":"{read_only:false; response_revision:24920; number_of_response:1; }","duration":"463.14353ms","start":"2026-05-09T02:25:37.556615Z","end":"2026-05-09T02:25:38.019758Z","steps":["trace[1894114719] 'process raft request' (duration: 462.713148ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:38.019883Z","caller":"traceutil/trace.go:171","msg":"trace[426871992] transaction","detail":"{read_only:false; response_revision:24921; number_of_response:1; }","duration":"461.308191ms","start":"2026-05-09T02:25:37.558562Z","end":"2026-05-09T02:25:38.01987Z","steps":["trace[426871992] 'process raft request' (duration: 461.119475ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:38.01986Z","caller":"traceutil/trace.go:171","msg":"trace[1471802309] linearizableReadLoop","detail":"{readStateIndex:25729; appliedIndex:25723; }","duration":"436.895381ms","start":"2026-05-09T02:25:37.582938Z","end":"2026-05-09T02:25:38.019833Z","steps":["trace[1471802309] 'read index received' (duration: 153.05973ms)","trace[1471802309] 'applied index is now lower than readState.Index' (duration: 283.834411ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:25:38.019925Z","caller":"traceutil/trace.go:171","msg":"trace[2099830436] transaction","detail":"{read_only:false; response_revision:24922; number_of_response:1; }","duration":"460.89375ms","start":"2026-05-09T02:25:37.55901Z","end":"2026-05-09T02:25:38.019904Z","steps":["trace[2099830436] 'process raft request' (duration: 460.765296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:38.019952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:37.556601Z","time spent":"463.276784ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:25:38.020017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:37.558992Z","time spent":"460.974532ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:25:38.02015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.19645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-09T02:25:38.020193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.754705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T02:25:38.020218Z","caller":"traceutil/trace.go:171","msg":"trace[2136094664] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24922; }","duration":"437.273092ms","start":"2026-05-09T02:25:37.582927Z","end":"2026-05-09T02:25:38.020201Z","steps":["trace[2136094664] 'agreement among raft nodes before linearized reading' (duration: 437.077647ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:38.020247Z","caller":"traceutil/trace.go:171","msg":"trace[100879759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24922; }","duration":"427.827857ms","start":"2026-05-09T02:25:37.592402Z","end":"2026-05-09T02:25:38.02023Z","steps":["trace[100879759] 'agreement among raft nodes before linearized reading' (duration: 427.680723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:38.019944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:37.558527Z","time spent":"461.384563ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:25:38.020273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:37.582897Z","time spent":"437.360824ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T02:25:38.020282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:37.592392Z","time spent":"427.882859ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T02:25:38.020265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.959432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-09T02:25:38.02034Z","caller":"traceutil/trace.go:171","msg":"trace[106527021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24922; }","duration":"431.094515ms","start":"2026-05-09T02:25:37.589226Z","end":"2026-05-09T02:25:38.02032Z","steps":["trace[106527021] 'agreement among raft nodes before linearized reading' (duration: 430.845498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:38.020392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:37.589207Z","time spent":"431.176428ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T02:25:38.020717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:37.550561Z","time spent":"469.180123ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T02:25:38.167369Z","caller":"traceutil/trace.go:171","msg":"trace[1503663859] transaction","detail":"{read_only:false; response_revision:24924; number_of_response:1; }","duration":"143.044901ms","start":"2026-05-09T02:25:38.0243Z","end":"2026-05-09T02:25:38.167345Z","steps":["trace[1503663859] 'process raft request' (duration: 141.347596ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:38.167588Z","caller":"traceutil/trace.go:171","msg":"trace[1077994393] transaction","detail":"{read_only:false; response_revision:24925; number_of_response:1; }","duration":"142.597468ms","start":"2026-05-09T02:25:38.024964Z","end":"2026-05-09T02:25:38.167562Z","steps":["trace[1077994393] 'process raft request' (duration: 142.339122ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:38.167987Z","caller":"traceutil/trace.go:171","msg":"trace[544935752] linearizableReadLoop","detail":"{readStateIndex:25734; appliedIndex:25730; }","duration":"125.037064ms","start":"2026-05-09T02:25:38.04293Z","end":"2026-05-09T02:25:38.167967Z","steps":["trace[544935752] 'read index received' (duration: 27.542964ms)","trace[544935752] 'applied index is now lower than readState.Index' (duration: 97.49305ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:25:38.168069Z","caller":"traceutil/trace.go:171","msg":"trace[197239263] transaction","detail":"{read_only:false; response_revision:24926; number_of_response:1; }","duration":"142.99652ms","start":"2026-05-09T02:25:38.025059Z","end":"2026-05-09T02:25:38.168056Z","steps":["trace[197239263] 'process raft request' (duration: 142.423374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:38.168111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.156687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T02:25:38.168118Z","caller":"traceutil/trace.go:171","msg":"trace[987765965] transaction","detail":"{read_only:false; response_revision:24927; number_of_response:1; }","duration":"142.982549ms","start":"2026-05-09T02:25:38.025122Z","end":"2026-05-09T02:25:38.168104Z","steps":["trace[987765965] 'process raft request' (duration: 142.736902ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:38.168139Z","caller":"traceutil/trace.go:171","msg":"trace[1195729116] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:24927; }","duration":"125.22912ms","start":"2026-05-09T02:25:38.0429Z","end":"2026-05-09T02:25:38.168129Z","steps":["trace[1195729116] 'agreement among raft nodes before linearized reading' (duration: 125.135587ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:38.72141Z","caller":"traceutil/trace.go:171","msg":"trace[1173780918] transaction","detail":"{read_only:false; response_revision:24928; number_of_response:1; }","duration":"207.338086ms","start":"2026-05-09T02:25:38.514003Z","end":"2026-05-09T02:25:38.721341Z","steps":["trace[1173780918] 'process raft request' (duration: 207.029598ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:40.181424Z","caller":"traceutil/trace.go:171","msg":"trace[1442463793] transaction","detail":"{read_only:false; response_revision:24934; number_of_response:1; }","duration":"154.753397ms","start":"2026-05-09T02:25:40.026653Z","end":"2026-05-09T02:25:40.181407Z","steps":["trace[1442463793] 'process raft request' (duration: 154.698115ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:40.18146Z","caller":"traceutil/trace.go:171","msg":"trace[1204968018] transaction","detail":"{read_only:false; response_revision:24933; number_of_response:1; }","duration":"155.034543ms","start":"2026-05-09T02:25:40.026403Z","end":"2026-05-09T02:25:40.181438Z","steps":["trace[1204968018] 'process raft request' (duration: 154.91465ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:40.181478Z","caller":"traceutil/trace.go:171","msg":"trace[1606459830] transaction","detail":"{read_only:false; response_revision:24932; number_of_response:1; }","duration":"155.079715ms","start":"2026-05-09T02:25:40.026364Z","end":"2026-05-09T02:25:40.181444Z","steps":["trace[1606459830] 'process raft request' (duration: 154.847418ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:40.181567Z","caller":"traceutil/trace.go:171","msg":"trace[655472377] linearizableReadLoop","detail":"{readStateIndex:25742; appliedIndex:25737; }","duration":"107.086009ms","start":"2026-05-09T02:25:40.074466Z","end":"2026-05-09T02:25:40.181552Z","steps":["trace[655472377] 'read index received' (duration: 15.646712ms)","trace[655472377] 'applied index is now lower than readState.Index' (duration: 91.437967ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:25:40.181608Z","caller":"traceutil/trace.go:171","msg":"trace[1777863439] transaction","detail":"{read_only:false; response_revision:24935; number_of_response:1; }","duration":"153.731789ms","start":"2026-05-09T02:25:40.027869Z","end":"2026-05-09T02:25:40.181601Z","steps":["trace[1777863439] 'process raft request' (duration: 153.512513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:40.181749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.279365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T02:25:40.181778Z","caller":"traceutil/trace.go:171","msg":"trace[1466865428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24935; }","duration":"107.324556ms","start":"2026-05-09T02:25:40.074446Z","end":"2026-05-09T02:25:40.181771Z","steps":["trace[1466865428] 'agreement among raft nodes before linearized reading' (duration: 107.183233ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:40.467798Z","caller":"traceutil/trace.go:171","msg":"trace[815204394] transaction","detail":"{read_only:false; response_revision:24940; number_of_response:1; }","duration":"280.032188ms","start":"2026-05-09T02:25:40.187745Z","end":"2026-05-09T02:25:40.467777Z","steps":["trace[815204394] 'process raft request' (duration: 279.682408ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:40.467885Z","caller":"traceutil/trace.go:171","msg":"trace[1242376813] transaction","detail":"{read_only:false; response_revision:24937; number_of_response:1; }","duration":"281.6156ms","start":"2026-05-09T02:25:40.186251Z","end":"2026-05-09T02:25:40.467866Z","steps":["trace[1242376813] 'process raft request' (duration: 198.063225ms)","trace[1242376813] 'compare' (duration: 82.899708ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:25:40.467932Z","caller":"traceutil/trace.go:171","msg":"trace[469725500] transaction","detail":"{read_only:false; response_revision:24938; number_of_response:1; }","duration":"281.58478ms","start":"2026-05-09T02:25:40.186306Z","end":"2026-05-09T02:25:40.467891Z","steps":["trace[469725500] 'process raft request' (duration: 281.049475ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:40.467814Z","caller":"traceutil/trace.go:171","msg":"trace[1139734818] transaction","detail":"{read_only:false; response_revision:24939; number_of_response:1; }","duration":"280.416199ms","start":"2026-05-09T02:25:40.187364Z","end":"2026-05-09T02:25:40.46778Z","steps":["trace[1139734818] 'process raft request' (duration: 280.034168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:42.548671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.473367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T02:25:42.549294Z","caller":"traceutil/trace.go:171","msg":"trace[1085889007] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"361.567168ms","start":"2026-05-09T02:25:42.187711Z","end":"2026-05-09T02:25:42.549278Z","steps":["trace[1085889007] 'process raft request' (duration: 361.296191ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:42.549309Z","caller":"traceutil/trace.go:171","msg":"trace[1505716001] transaction","detail":"{read_only:false; response_revision:24945; number_of_response:1; }","duration":"362.237026ms","start":"2026-05-09T02:25:42.187051Z","end":"2026-05-09T02:25:42.549288Z","steps":["trace[1505716001] 'process raft request' (duration: 146.930456ms)","trace[1505716001] 'compare' (duration: 213.990145ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:25:42.549294Z","caller":"traceutil/trace.go:171","msg":"trace[1129467265] transaction","detail":"{read_only:false; response_revision:24946; number_of_response:1; }","duration":"361.796814ms","start":"2026-05-09T02:25:42.187462Z","end":"2026-05-09T02:25:42.549259Z","steps":["trace[1129467265] 'process raft request' (duration: 361.332732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:42.5494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:42.187702Z","time spent":"361.64257ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:25:42.549401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:42.187024Z","time spent":"362.320238ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T02:25:42.54938Z","caller":"traceutil/trace.go:171","msg":"trace[1817723397] transaction","detail":"{read_only:false; response_revision:24948; number_of_response:1; }","duration":"360.691784ms","start":"2026-05-09T02:25:42.188652Z","end":"2026-05-09T02:25:42.549344Z","steps":["trace[1817723397] 'process raft request' (duration: 360.400047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:42.549421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:42.18745Z","time spent":"361.901337ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:25:42.549557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:42.188624Z","time spent":"360.830197ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T02:25:42.688606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.465415ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-09T02:25:42.688795Z","caller":"traceutil/trace.go:171","msg":"trace[1947921065] linearizableReadLoop","detail":"{readStateIndex:25756; appliedIndex:25751; }","duration":"456.315886ms","start":"2026-05-09T02:25:42.232464Z","end":"2026-05-09T02:25:42.68878Z","steps":["trace[1947921065] 'read index received' (duration: 101.595393ms)","trace[1947921065] 'applied index is now lower than readState.Index' (duration: 354.719023ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:25:42.688904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.429699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:25:42.688932Z","caller":"traceutil/trace.go:171","msg":"trace[932411984] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24948; }","duration":"456.498361ms","start":"2026-05-09T02:25:42.232425Z","end":"2026-05-09T02:25:42.688924Z","steps":["trace[932411984] 'agreement among raft nodes before linearized reading' (duration: 456.432699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:42.689091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.664619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-09T02:25:42.689168Z","caller":"traceutil/trace.go:171","msg":"trace[874988553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24948; }","duration":"445.769771ms","start":"2026-05-09T02:25:42.24338Z","end":"2026-05-09T02:25:42.68915Z","steps":["trace[874988553] 'agreement among raft nodes before linearized reading' (duration: 445.556595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:42.689214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:42.243368Z","time spent":"445.837893ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T02:25:42.689462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.333969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-09T02:25:42.68953Z","caller":"traceutil/trace.go:171","msg":"trace[190066814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24948; }","duration":"216.408371ms","start":"2026-05-09T02:25:42.473113Z","end":"2026-05-09T02:25:42.689521Z","steps":["trace[190066814] 'agreement among raft nodes before linearized reading' (duration: 216.277027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:42.689637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.333559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-09T02:25:42.68947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.893509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-09T02:25:42.689751Z","caller":"traceutil/trace.go:171","msg":"trace[79977638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24948; }","duration":"216.549125ms","start":"2026-05-09T02:25:42.473181Z","end":"2026-05-09T02:25:42.68973Z","steps":["trace[79977638] 'agreement among raft nodes before linearized reading' (duration: 216.108173ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:42.689769Z","caller":"traceutil/trace.go:171","msg":"trace[400634098] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:24948; }","duration":"169.239319ms","start":"2026-05-09T02:25:42.520513Z","end":"2026-05-09T02:25:42.689753Z","steps":["trace[400634098] 'agreement among raft nodes before linearized reading' (duration: 168.789886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:42.689817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:42.232411Z","time spent":"457.392985ms","remote":"127.0.0.1:59444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-09T02:25:42.689819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.528965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-09T02:25:42.690064Z","caller":"traceutil/trace.go:171","msg":"trace[1908600219] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24948; }","duration":"216.776972ms","start":"2026-05-09T02:25:42.473271Z","end":"2026-05-09T02:25:42.690048Z","steps":["trace[1908600219] 'agreement among raft nodes before linearized reading' (duration: 216.467524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:42.689865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.618072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:25:42.69021Z","caller":"traceutil/trace.go:171","msg":"trace[443488659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24948; }","duration":"437.945901ms","start":"2026-05-09T02:25:42.252219Z","end":"2026-05-09T02:25:42.690165Z","steps":["trace[443488659] 'agreement among raft nodes before linearized reading' (duration: 437.620642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:42.690314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T02:25:42.25221Z","time spent":"438.070374ms","remote":"127.0.0.1:59002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T02:25:42.690322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.234844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-09T02:25:42.690359Z","caller":"traceutil/trace.go:171","msg":"trace[988203739] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24948; }","duration":"217.296056ms","start":"2026-05-09T02:25:42.473052Z","end":"2026-05-09T02:25:42.690348Z","steps":["trace[988203739] 'agreement among raft nodes before linearized reading' (duration: 216.592886ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:42.813835Z","caller":"traceutil/trace.go:171","msg":"trace[1371825156] transaction","detail":"{read_only:false; response_revision:24949; number_of_response:1; }","duration":"119.783884ms","start":"2026-05-09T02:25:42.694024Z","end":"2026-05-09T02:25:42.813808Z","steps":["trace[1371825156] 'process raft request' (duration: 119.615719ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:42.844962Z","caller":"traceutil/trace.go:171","msg":"trace[827334032] linearizableReadLoop","detail":"{readStateIndex:25761; appliedIndex:25756; }","duration":"148.549469ms","start":"2026-05-09T02:25:42.69639Z","end":"2026-05-09T02:25:42.844939Z","steps":["trace[827334032] 'read index received' (duration: 117.255274ms)","trace[827334032] 'applied index is now lower than readState.Index' (duration: 31.293295ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:25:42.845261Z","caller":"traceutil/trace.go:171","msg":"trace[1599640142] transaction","detail":"{read_only:false; response_revision:24950; number_of_response:1; }","duration":"151.041627ms","start":"2026-05-09T02:25:42.694205Z","end":"2026-05-09T02:25:42.845247Z","steps":["trace[1599640142] 'process raft request' (duration: 150.448341ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:42.845445Z","caller":"traceutil/trace.go:171","msg":"trace[1296261427] transaction","detail":"{read_only:false; response_revision:24951; number_of_response:1; }","duration":"150.715598ms","start":"2026-05-09T02:25:42.69472Z","end":"2026-05-09T02:25:42.845435Z","steps":["trace[1296261427] 'process raft request' (duration: 150.05913ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:42.845623Z","caller":"traceutil/trace.go:171","msg":"trace[1690075734] transaction","detail":"{read_only:false; response_revision:24952; number_of_response:1; }","duration":"149.68486ms","start":"2026-05-09T02:25:42.695928Z","end":"2026-05-09T02:25:42.845613Z","steps":["trace[1690075734] 'process raft request' (duration: 148.888528ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:42.847417Z","caller":"traceutil/trace.go:171","msg":"trace[349734608] transaction","detail":"{read_only:false; response_revision:24953; number_of_response:1; }","duration":"151.112498ms","start":"2026-05-09T02:25:42.696287Z","end":"2026-05-09T02:25:42.8474Z","steps":["trace[349734608] 'process raft request' (duration: 148.57171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:42.847743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.335904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-09T02:25:42.84779Z","caller":"traceutil/trace.go:171","msg":"trace[755500768] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24954; }","duration":"151.392805ms","start":"2026-05-09T02:25:42.696385Z","end":"2026-05-09T02:25:42.847778Z","steps":["trace[755500768] 'agreement among raft nodes before linearized reading' (duration: 151.264812ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:43.044805Z","caller":"traceutil/trace.go:171","msg":"trace[1414396506] linearizableReadLoop","detail":"{readStateIndex:25763; appliedIndex:25762; }","duration":"183.765169ms","start":"2026-05-09T02:25:42.861014Z","end":"2026-05-09T02:25:43.044779Z","steps":["trace[1414396506] 'read index received' (duration: 183.636366ms)","trace[1414396506] 'applied index is now lower than readState.Index' (duration: 127.643µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:25:43.044835Z","caller":"traceutil/trace.go:171","msg":"trace[332748216] transaction","detail":"{read_only:false; response_revision:24955; number_of_response:1; }","duration":"189.000531ms","start":"2026-05-09T02:25:42.855807Z","end":"2026-05-09T02:25:43.044807Z","steps":["trace[332748216] 'process raft request' (duration: 188.798665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:43.045032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.987495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-05-09T02:25:43.045092Z","caller":"traceutil/trace.go:171","msg":"trace[1511698447] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24955; }","duration":"184.080948ms","start":"2026-05-09T02:25:42.860994Z","end":"2026-05-09T02:25:43.045075Z","steps":["trace[1511698447] 'agreement among raft nodes before linearized reading' (duration: 183.882043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:43.31839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.364788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-rt8f9\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-05-09T02:25:43.318451Z","caller":"traceutil/trace.go:171","msg":"trace[328845164] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-rt8f9; range_end:; response_count:1; response_revision:24956; }","duration":"120.457061ms","start":"2026-05-09T02:25:43.19798Z","end":"2026-05-09T02:25:43.318437Z","steps":["trace[328845164] 'range keys from in-memory index tree' (duration: 120.212215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:43.319072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.839084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:136 size:1318779"} {"level":"info","ts":"2026-05-09T02:25:43.319124Z","caller":"traceutil/trace.go:171","msg":"trace[561497675] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:136; response_revision:24956; }","duration":"232.928206ms","start":"2026-05-09T02:25:43.086184Z","end":"2026-05-09T02:25:43.319112Z","steps":["trace[561497675] 'range keys from in-memory index tree' (duration: 232.094164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:44.443633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.183237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:25:44.443751Z","caller":"traceutil/trace.go:171","msg":"trace[1052290961] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:24959; }","duration":"149.348171ms","start":"2026-05-09T02:25:44.294382Z","end":"2026-05-09T02:25:44.44373Z","steps":["trace[1052290961] 'count revisions from in-memory index tree' (duration: 149.116275ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:44.938136Z","caller":"traceutil/trace.go:171","msg":"trace[1761871512] transaction","detail":"{read_only:false; response_revision:24966; number_of_response:1; }","duration":"116.427581ms","start":"2026-05-09T02:25:44.821672Z","end":"2026-05-09T02:25:44.938099Z","steps":["trace[1761871512] 'process raft request' (duration: 116.170704ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:45.054523Z","caller":"traceutil/trace.go:171","msg":"trace[1625666487] transaction","detail":"{read_only:false; response_revision:24968; number_of_response:1; }","duration":"112.28537ms","start":"2026-05-09T02:25:44.942215Z","end":"2026-05-09T02:25:45.054501Z","steps":["trace[1625666487] 'process raft request' (duration: 112.235709ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:45.054582Z","caller":"traceutil/trace.go:171","msg":"trace[1792738802] transaction","detail":"{read_only:false; response_revision:24967; number_of_response:1; }","duration":"200.931723ms","start":"2026-05-09T02:25:44.853612Z","end":"2026-05-09T02:25:45.054544Z","steps":["trace[1792738802] 'process raft request' (duration: 139.710171ms)","trace[1792738802] 'compare' (duration: 60.964976ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T02:25:45.054645Z","caller":"traceutil/trace.go:171","msg":"trace[1187224456] linearizableReadLoop","detail":"{readStateIndex:25775; appliedIndex:25774; }","duration":"116.571607ms","start":"2026-05-09T02:25:44.938051Z","end":"2026-05-09T02:25:45.054623Z","steps":["trace[1187224456] 'read index received' (duration: 55.285862ms)","trace[1187224456] 'applied index is now lower than readState.Index' (duration: 61.283495ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T02:25:45.05472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.199476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-09T02:25:45.054757Z","caller":"traceutil/trace.go:171","msg":"trace[172774545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24968; }","duration":"199.263748ms","start":"2026-05-09T02:25:44.855485Z","end":"2026-05-09T02:25:45.054749Z","steps":["trace[172774545] 'agreement among raft nodes before linearized reading' (duration: 199.143365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:45.054799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.017139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-09T02:25:45.054809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.155272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T02:25:45.054855Z","caller":"traceutil/trace.go:171","msg":"trace[1307102406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24968; }","duration":"200.09672ms","start":"2026-05-09T02:25:44.854743Z","end":"2026-05-09T02:25:45.054839Z","steps":["trace[1307102406] 'agreement among raft nodes before linearized reading' (duration: 199.944416ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:45.054859Z","caller":"traceutil/trace.go:171","msg":"trace[1254135945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24968; }","duration":"200.248445ms","start":"2026-05-09T02:25:44.854598Z","end":"2026-05-09T02:25:45.054846Z","steps":["trace[1254135945] 'agreement among raft nodes before linearized reading' (duration: 200.116571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:46.328468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.854967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:25:46.328779Z","caller":"traceutil/trace.go:171","msg":"trace[2142127026] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24976; }","duration":"116.222958ms","start":"2026-05-09T02:25:46.212535Z","end":"2026-05-09T02:25:46.328758Z","steps":["trace[2142127026] 'agreement among raft nodes before linearized reading' (duration: 115.838206ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:25:46.328683Z","caller":"traceutil/trace.go:171","msg":"trace[724065867] transaction","detail":"{read_only:false; response_revision:24976; number_of_response:1; }","duration":"134.962542ms","start":"2026-05-09T02:25:46.193675Z","end":"2026-05-09T02:25:46.328637Z","steps":["trace[724065867] 'process raft request' (duration: 134.572362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T02:25:47.356775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.87175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T02:25:47.356873Z","caller":"traceutil/trace.go:171","msg":"trace[513298249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24988; }","duration":"105.029955ms","start":"2026-05-09T02:25:47.251823Z","end":"2026-05-09T02:25:47.356853Z","steps":["trace[513298249] 'range keys from in-memory index tree' (duration: 104.752537ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:27:43.648493Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23235} {"level":"info","ts":"2026-05-09T02:27:43.795798Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23235,"took":"143.333578ms","hash":3421680272,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":36335616,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-09T02:27:43.795857Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3421680272,"revision":23235,"compact-revision":20016} {"level":"info","ts":"2026-05-09T02:28:19.714075Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006abe.wal"} {"level":"info","ts":"2026-05-09T02:29:00.201389Z","caller":"traceutil/trace.go:171","msg":"trace[1748137320] transaction","detail":"{read_only:false; response_revision:26921; number_of_response:1; }","duration":"114.953224ms","start":"2026-05-09T02:29:00.08641Z","end":"2026-05-09T02:29:00.201363Z","steps":["trace[1748137320] 'process raft request' (duration: 109.300181ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T02:32:43.654477Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26122} {"level":"info","ts":"2026-05-09T02:32:43.8009Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26122,"took":"143.136913ms","hash":2579336710,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":33173504,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-09T02:32:43.800983Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2579336710,"revision":26122,"compact-revision":23235} {"level":"info","ts":"2026-05-09T02:33:33.627127Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a847c2e9c7010db6","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T02:33:33.632157Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-09T02:33:33.63232Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-09T02:37:43.660578Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28718} {"level":"info","ts":"2026-05-09T02:37:43.795444Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28718,"took":"130.915771ms","hash":3020359661,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":29917184,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T02:37:43.795526Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3020359661,"revision":28718,"compact-revision":26122} {"level":"info","ts":"2026-05-09T02:42:43.66609Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30939} {"level":"info","ts":"2026-05-09T02:42:43.784078Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30939,"took":"113.342375ms","hash":246157142,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":31690752,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T02:42:43.784211Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":246157142,"revision":30939,"compact-revision":28718} {"level":"info","ts":"2026-05-09T02:47:43.673117Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33269} {"level":"info","ts":"2026-05-09T02:47:43.822929Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33269,"took":"145.146531ms","hash":1536693862,"current-db-size-bytes":59101184,"current-db-size":"59 MB","current-db-size-in-use-bytes":31490048,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T02:47:43.823008Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1536693862,"revision":33269,"compact-revision":30939}