{"level":"warn","ts":"2026-06-30T09:02:56.390922Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-30T09:02:56.391015Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.242:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.242:2380","--initial-cluster=instance=https://199.19.213.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.242:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-30T09:02:56.391106Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-30T09:02:56.391118Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.242:2380"]} {"level":"info","ts":"2026-06-30T09:02:56.391146Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-30T09:02:56.391963Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.242:2379"]} {"level":"info","ts":"2026-06-30T09:02:56.392072Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.242:2380"],"listen-peer-urls":["https://199.19.213.242:2380"],"advertise-client-urls":["https://199.19.213.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.242:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-30T09:02:56.395588Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.201454ms"} {"level":"info","ts":"2026-06-30T09:02:56.401397Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ad427454a89beddc","cluster-id":"edc6037504b39883"} {"level":"info","ts":"2026-06-30T09:02:56.401543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc switched to configuration voters=()"} {"level":"info","ts":"2026-06-30T09:02:56.401572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became follower at term 0"} {"level":"info","ts":"2026-06-30T09:02:56.401593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ad427454a89beddc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-30T09:02:56.401608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became follower at term 1"} {"level":"info","ts":"2026-06-30T09:02:56.401663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc switched to configuration voters=(12484669023979302364)"} {"level":"warn","ts":"2026-06-30T09:02:56.406389Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-30T09:02:56.407842Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-30T09:02:56.408803Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-30T09:02:56.410605Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ad427454a89beddc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-30T09:02:56.410782Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-30T09:02:56.410875Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-30T09:02:56.410898Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-30T09:02:56.410856Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ad427454a89beddc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-30T09:02:56.412464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc switched to configuration voters=(12484669023979302364)"} {"level":"info","ts":"2026-06-30T09:02:56.412893Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"edc6037504b39883","local-member-id":"ad427454a89beddc","added-peer-id":"ad427454a89beddc","added-peer-peer-urls":["https://199.19.213.242:2380"]} {"level":"info","ts":"2026-06-30T09:02:56.415233Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-30T09:02:56.415429Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.242:2380"} {"level":"info","ts":"2026-06-30T09:02:56.415501Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.242:2380"} {"level":"info","ts":"2026-06-30T09:02:56.415561Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ad427454a89beddc","initial-advertise-peer-urls":["https://199.19.213.242:2380"],"listen-peer-urls":["https://199.19.213.242:2380"],"advertise-client-urls":["https://199.19.213.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-30T09:02:56.415627Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-30T09:02:57.302013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc is starting a new election at term 1"} {"level":"info","ts":"2026-06-30T09:02:57.302096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became pre-candidate at term 1"} {"level":"info","ts":"2026-06-30T09:02:57.302143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc received MsgPreVoteResp from ad427454a89beddc at term 1"} {"level":"info","ts":"2026-06-30T09:02:57.302166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became candidate at term 2"} {"level":"info","ts":"2026-06-30T09:02:57.302177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc received MsgVoteResp from ad427454a89beddc at term 2"} {"level":"info","ts":"2026-06-30T09:02:57.302196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became leader at term 2"} {"level":"info","ts":"2026-06-30T09:02:57.302221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ad427454a89beddc elected leader ad427454a89beddc at term 2"} {"level":"info","ts":"2026-06-30T09:02:57.303483Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ad427454a89beddc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.242:2379]}","request-path":"/0/members/ad427454a89beddc/attributes","cluster-id":"edc6037504b39883","publish-timeout":"7s"} {"level":"info","ts":"2026-06-30T09:02:57.303552Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-30T09:02:57.303531Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-30T09:02:57.303823Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-30T09:02:57.303863Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-30T09:02:57.304315Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T09:02:57.305451Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.242:2379"} {"level":"info","ts":"2026-06-30T09:02:57.305754Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"edc6037504b39883","local-member-id":"ad427454a89beddc","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T09:02:57.30586Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T09:02:57.305905Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T09:02:57.307517Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-30T09:03:20.994323Z","caller":"traceutil/trace.go:171","msg":"trace[16110111] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:407; }","duration":"161.900618ms","start":"2026-06-30T09:03:20.832403Z","end":"2026-06-30T09:03:20.994304Z","steps":["trace[16110111] 'read index received' (duration: 150.456132ms)","trace[16110111] 'applied index is now lower than readState.Index' (duration: 11.443676ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:03:20.994505Z","caller":"traceutil/trace.go:171","msg":"trace[2130133508] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"242.523392ms","start":"2026-06-30T09:03:20.751971Z","end":"2026-06-30T09:03:20.994494Z","steps":["trace[2130133508] 'process raft request' (duration: 230.84816ms)","trace[2130133508] 'compare' (duration: 11.209769ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:03:20.994666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.420983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-30T09:03:20.994854Z","caller":"traceutil/trace.go:171","msg":"trace[657698247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:397; }","duration":"161.677891ms","start":"2026-06-30T09:03:20.83315Z","end":"2026-06-30T09:03:20.994827Z","steps":["trace[657698247] 'agreement among raft nodes before linearized reading' (duration: 161.381922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:03:20.99473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.288999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-30T09:03:20.995007Z","caller":"traceutil/trace.go:171","msg":"trace[340001861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:397; }","duration":"162.6283ms","start":"2026-06-30T09:03:20.832342Z","end":"2026-06-30T09:03:20.99497Z","steps":["trace[340001861] 'agreement among raft nodes before linearized reading' (duration: 162.229177ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:03:20.995521Z","caller":"traceutil/trace.go:171","msg":"trace[479340262] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"242.220384ms","start":"2026-06-30T09:03:20.753279Z","end":"2026-06-30T09:03:20.9955Z","steps":["trace[479340262] 'process raft request' (duration: 240.969607ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:03:34.614049Z","caller":"traceutil/trace.go:171","msg":"trace[1196844173] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"101.299241ms","start":"2026-06-30T09:03:34.512724Z","end":"2026-06-30T09:03:34.614023Z","steps":["trace[1196844173] 'process raft request' (duration: 96.099177ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:03:40.48702Z","caller":"traceutil/trace.go:171","msg":"trace[1752567612] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"129.301204ms","start":"2026-06-30T09:03:40.357688Z","end":"2026-06-30T09:03:40.486989Z","steps":["trace[1752567612] 'read index received' (duration: 129.235502ms)","trace[1752567612] 'applied index is now lower than readState.Index' (duration: 63.592µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:03:40.487113Z","caller":"traceutil/trace.go:171","msg":"trace[1489972193] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"137.785655ms","start":"2026-06-30T09:03:40.349303Z","end":"2026-06-30T09:03:40.487088Z","steps":["trace[1489972193] 'process raft request' (duration: 137.474175ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:03:40.487243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.530682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-30T09:03:40.487382Z","caller":"traceutil/trace.go:171","msg":"trace[1311953553] range","detail":"{range_begin:/registry/masterleases/199.19.213.242; range_end:; response_count:1; response_revision:594; }","duration":"129.706687ms","start":"2026-06-30T09:03:40.357643Z","end":"2026-06-30T09:03:40.48735Z","steps":["trace[1311953553] 'agreement among raft nodes before linearized reading' (duration: 129.478181ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:03:40.57394Z","caller":"traceutil/trace.go:171","msg":"trace[1150852108] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"106.492104ms","start":"2026-06-30T09:03:40.467426Z","end":"2026-06-30T09:03:40.573918Z","steps":["trace[1150852108] 'process raft request' (duration: 106.036609ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:03:40.905795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.877678ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-06-30T09:03:40.905907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:03:40.488445Z","time spent":"417.456153ms","remote":"127.0.0.1:58542","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-30T09:03:40.906039Z","caller":"traceutil/trace.go:171","msg":"trace[761336942] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"268.256114ms","start":"2026-06-30T09:03:40.637765Z","end":"2026-06-30T09:03:40.906021Z","steps":["trace[761336942] 'process raft request' (duration: 268.13717ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:03:40.906067Z","caller":"traceutil/trace.go:171","msg":"trace[832898334] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:612; }","duration":"356.130059ms","start":"2026-06-30T09:03:40.549861Z","end":"2026-06-30T09:03:40.905991Z","steps":["trace[832898334] 'read index received' (duration: 23.623995ms)","trace[832898334] 'applied index is now lower than readState.Index' (duration: 332.503844ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:03:40.906363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.488309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-30T09:03:40.906417Z","caller":"traceutil/trace.go:171","msg":"trace[1049266631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:596; }","duration":"356.611923ms","start":"2026-06-30T09:03:40.549795Z","end":"2026-06-30T09:03:40.906407Z","steps":["trace[1049266631] 'agreement among raft nodes before linearized reading' (duration: 356.355165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:03:40.906444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:03:40.549765Z","time spent":"356.672954ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-30T09:03:41.390928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.776624ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T09:03:41.39109Z","caller":"traceutil/trace.go:171","msg":"trace[1271529655] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:598; }","duration":"276.969159ms","start":"2026-06-30T09:03:41.114101Z","end":"2026-06-30T09:03:41.39107Z","steps":["trace[1271529655] 'range keys from in-memory index tree' (duration: 276.754763ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:03:41.391167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.136327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-30T09:03:41.391277Z","caller":"traceutil/trace.go:171","msg":"trace[779032013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"272.26569ms","start":"2026-06-30T09:03:41.11895Z","end":"2026-06-30T09:03:41.391216Z","steps":["trace[779032013] 'range keys from in-memory index tree' (duration: 271.965722ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:03:41.391304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.239921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-30T09:03:41.391367Z","caller":"traceutil/trace.go:171","msg":"trace[614009414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"272.353683ms","start":"2026-06-30T09:03:41.118998Z","end":"2026-06-30T09:03:41.391352Z","steps":["trace[614009414] 'range keys from in-memory index tree' (duration: 272.070165ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:04:47.860094Z","caller":"traceutil/trace.go:171","msg":"trace[1543031169] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"111.337588ms","start":"2026-06-30T09:04:47.748724Z","end":"2026-06-30T09:04:47.860062Z","steps":["trace[1543031169] 'process raft request' (duration: 111.150413ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:04:47.872062Z","caller":"traceutil/trace.go:171","msg":"trace[224200049] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"119.998873ms","start":"2026-06-30T09:04:47.752042Z","end":"2026-06-30T09:04:47.872041Z","steps":["trace[224200049] 'process raft request' (duration: 119.89154ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:04:59.50359Z","caller":"traceutil/trace.go:171","msg":"trace[1226589597] transaction","detail":"{read_only:false; response_revision:1642; number_of_response:1; }","duration":"248.260868ms","start":"2026-06-30T09:04:59.255303Z","end":"2026-06-30T09:04:59.503563Z","steps":["trace[1226589597] 'process raft request' (duration: 248.115684ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:04:59.503666Z","caller":"traceutil/trace.go:171","msg":"trace[789097618] linearizableReadLoop","detail":"{readStateIndex:1699; appliedIndex:1699; }","duration":"194.84582ms","start":"2026-06-30T09:04:59.308754Z","end":"2026-06-30T09:04:59.5036Z","steps":["trace[789097618] 'read index received' (duration: 194.82917ms)","trace[789097618] 'applied index is now lower than readState.Index' (duration: 13.67µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:04:59.504105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.342645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-30T09:04:59.504155Z","caller":"traceutil/trace.go:171","msg":"trace[1439115452] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1642; }","duration":"195.449788ms","start":"2026-06-30T09:04:59.308692Z","end":"2026-06-30T09:04:59.504142Z","steps":["trace[1439115452] 'agreement among raft nodes before linearized reading' (duration: 195.088538ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:05:00.120033Z","caller":"traceutil/trace.go:171","msg":"trace[1511188469] transaction","detail":"{read_only:false; response_revision:1643; number_of_response:1; }","duration":"804.479998ms","start":"2026-06-30T09:04:59.315529Z","end":"2026-06-30T09:05:00.120009Z","steps":["trace[1511188469] 'process raft request' (duration: 804.20686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:05:00.120596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:04:59.315509Z","time spent":"804.59418ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T09:05:00.121389Z","caller":"traceutil/trace.go:171","msg":"trace[1967747145] linearizableReadLoop","detail":"{readStateIndex:1701; appliedIndex:1699; }","duration":"270.169641ms","start":"2026-06-30T09:04:59.851198Z","end":"2026-06-30T09:05:00.121368Z","steps":["trace[1967747145] 'read index received' (duration: 268.504933ms)","trace[1967747145] 'applied index is now lower than readState.Index' (duration: 1.664128ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:05:00.121574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.358707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T09:05:00.121625Z","caller":"traceutil/trace.go:171","msg":"trace[294055342] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1644; }","duration":"270.46852ms","start":"2026-06-30T09:04:59.851139Z","end":"2026-06-30T09:05:00.121607Z","steps":["trace[294055342] 'agreement among raft nodes before linearized reading' (duration: 270.361677ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:05:00.121642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.038831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterbackups.pxc.percona.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T09:05:00.121701Z","caller":"traceutil/trace.go:171","msg":"trace[1787989318] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterbackups.pxc.percona.com; range_end:; response_count:0; response_revision:1644; }","duration":"170.156354ms","start":"2026-06-30T09:04:59.951533Z","end":"2026-06-30T09:05:00.12169Z","steps":["trace[1787989318] 'agreement among raft nodes before linearized reading' (duration: 170.032771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:05:00.121617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.794699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-30T09:05:00.121761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.263667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-30T09:05:00.121801Z","caller":"traceutil/trace.go:171","msg":"trace[1648293260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1644; }","duration":"202.34154ms","start":"2026-06-30T09:04:59.919451Z","end":"2026-06-30T09:05:00.121793Z","steps":["trace[1648293260] 'agreement among raft nodes before linearized reading' (duration: 202.194566ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:05:00.121834Z","caller":"traceutil/trace.go:171","msg":"trace[1375155977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1644; }","duration":"209.007125ms","start":"2026-06-30T09:04:59.91278Z","end":"2026-06-30T09:05:00.121787Z","steps":["trace[1375155977] 'agreement among raft nodes before linearized reading' (duration: 208.763408ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:05:00.121869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.512165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T09:05:00.121951Z","caller":"traceutil/trace.go:171","msg":"trace[1753700291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1644; }","duration":"155.654839ms","start":"2026-06-30T09:04:59.966277Z","end":"2026-06-30T09:05:00.121932Z","steps":["trace[1753700291] 'agreement among raft nodes before linearized reading' (duration: 155.493085ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:05:00.12206Z","caller":"traceutil/trace.go:171","msg":"trace[194846168] transaction","detail":"{read_only:false; response_revision:1644; number_of_response:1; }","duration":"613.346766ms","start":"2026-06-30T09:04:59.50814Z","end":"2026-06-30T09:05:00.121487Z","steps":["trace[194846168] 'process raft request' (duration: 613.12525ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:05:00.1222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:04:59.50812Z","time spent":"613.991415ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T09:05:04.758374Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17139749206293716513,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-30T09:05:05.259001Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17139749206293716513,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-30T09:05:05.386814Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.225149809s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-30T09:05:05.388211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.386226207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T09:05:05.38828Z","caller":"traceutil/trace.go:171","msg":"trace[1277051686] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1669; }","duration":"1.38634258s","start":"2026-06-30T09:05:04.001918Z","end":"2026-06-30T09:05:05.388261Z","steps":["trace[1277051686] 'count revisions from in-memory index tree' (duration: 1.386153886s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:05:05.388319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:05:04.001899Z","time spent":"1.386411253s","remote":"127.0.0.1:58730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-06-30T09:05:05.389837Z","caller":"traceutil/trace.go:171","msg":"trace[1375807239] transaction","detail":"{read_only:false; response_revision:1670; number_of_response:1; }","duration":"1.228321432s","start":"2026-06-30T09:05:04.161498Z","end":"2026-06-30T09:05:05.38982Z","steps":["trace[1375807239] 'process raft request' (duration: 1.225561131s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:05:05.389933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:05:04.161455Z","time spent":"1.228413514s","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T09:05:05.395877Z","caller":"traceutil/trace.go:171","msg":"trace[984399143] linearizableReadLoop","detail":"{readStateIndex:1729; appliedIndex:1727; }","duration":"1.140553554s","start":"2026-06-30T09:05:04.255288Z","end":"2026-06-30T09:05:05.395842Z","steps":["trace[984399143] 'read index received' (duration: 1.131788907s)","trace[984399143] 'applied index is now lower than readState.Index' (duration: 8.763697ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:05:05.396154Z","caller":"traceutil/trace.go:171","msg":"trace[27780201] transaction","detail":"{read_only:false; response_revision:1671; number_of_response:1; }","duration":"367.986133ms","start":"2026-06-30T09:05:05.028136Z","end":"2026-06-30T09:05:05.396122Z","steps":["trace[27780201] 'process raft request' (duration: 367.574811ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:05:05.39639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:05:05.02811Z","time spent":"368.122228ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T09:05:05.396627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141294876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:2 size:146770"} {"level":"info","ts":"2026-06-30T09:05:05.396751Z","caller":"traceutil/trace.go:171","msg":"trace[606851595] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:2; response_revision:1671; }","duration":"1.14144225s","start":"2026-06-30T09:05:04.255284Z","end":"2026-06-30T09:05:05.396726Z","steps":["trace[606851595] 'agreement among raft nodes before linearized reading' (duration: 1.140915175s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:05:05.396818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:05:04.255245Z","time spent":"1.141558674s","remote":"127.0.0.1:58598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":2,"response size":146794,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"warn","ts":"2026-06-30T09:05:05.404815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065725267s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T09:05:05.404891Z","caller":"traceutil/trace.go:171","msg":"trace[1447341221] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1672; }","duration":"1.065853111s","start":"2026-06-30T09:05:04.33902Z","end":"2026-06-30T09:05:05.404873Z","steps":["trace[1447341221] 'agreement among raft nodes before linearized reading' (duration: 1.065679496s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:05:05.404923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:05:04.339002Z","time spent":"1.065914262s","remote":"127.0.0.1:58680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":19,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-06-30T09:05:05.405483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.47048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T09:05:05.405506Z","caller":"traceutil/trace.go:171","msg":"trace[1101654921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1672; }","duration":"441.537062ms","start":"2026-06-30T09:05:04.963962Z","end":"2026-06-30T09:05:05.405499Z","steps":["trace[1101654921] 'agreement among raft nodes before linearized reading' (duration: 441.500401ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:05:05.405523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:05:04.963928Z","time spent":"441.590814ms","remote":"127.0.0.1:58492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T09:05:05.405528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.697193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-controller-ssq7q\" ","response":"range_response_count:1 size:7308"} {"level":"info","ts":"2026-06-30T09:05:05.405565Z","caller":"traceutil/trace.go:171","msg":"trace[1312898167] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-controller-ssq7q; range_end:; response_count:1; response_revision:1672; }","duration":"167.762985ms","start":"2026-06-30T09:05:05.237783Z","end":"2026-06-30T09:05:05.405546Z","steps":["trace[1312898167] 'agreement among raft nodes before linearized reading' (duration: 167.60781ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:05:20.119924Z","caller":"traceutil/trace.go:171","msg":"trace[265846441] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"203.425803ms","start":"2026-06-30T09:05:19.916455Z","end":"2026-06-30T09:05:20.119881Z","steps":["trace[265846441] 'process raft request' (duration: 203.111144ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:05:20.120229Z","caller":"traceutil/trace.go:171","msg":"trace[191121220] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"203.663569ms","start":"2026-06-30T09:05:19.916537Z","end":"2026-06-30T09:05:20.1202Z","steps":["trace[191121220] 'process raft request' (duration: 203.273398ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:05:20.120564Z","caller":"traceutil/trace.go:171","msg":"trace[364186695] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"201.624099ms","start":"2026-06-30T09:05:19.918922Z","end":"2026-06-30T09:05:20.120546Z","steps":["trace[364186695] 'process raft request' (duration: 201.169026ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:05:20.120676Z","caller":"traceutil/trace.go:171","msg":"trace[1674940375] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1921; }","duration":"202.899777ms","start":"2026-06-30T09:05:19.917761Z","end":"2026-06-30T09:05:20.120661Z","steps":["trace[1674940375] 'read index received' (duration: 6.676356ms)","trace[1674940375] 'applied index is now lower than readState.Index' (duration: 196.222091ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:05:20.120816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.402882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T09:05:20.12084Z","caller":"traceutil/trace.go:171","msg":"trace[1532832181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1860; }","duration":"156.483595ms","start":"2026-06-30T09:05:19.964348Z","end":"2026-06-30T09:05:20.120832Z","steps":["trace[1532832181] 'agreement among raft nodes before linearized reading' (duration: 156.418723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:05:20.120915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.184807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ce9a7a10-55ed-4e57-be87-0b1c34a95611\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-06-30T09:05:20.120908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.121253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-30T09:05:20.120954Z","caller":"traceutil/trace.go:171","msg":"trace[2096447671] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ce9a7a10-55ed-4e57-be87-0b1c34a95611; range_end:; response_count:1; response_revision:1860; }","duration":"105.26695ms","start":"2026-06-30T09:05:20.015674Z","end":"2026-06-30T09:05:20.120941Z","steps":["trace[2096447671] 'agreement among raft nodes before linearized reading' (duration: 105.124866ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:05:20.121006Z","caller":"traceutil/trace.go:171","msg":"trace[1249525608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1860; }","duration":"203.234816ms","start":"2026-06-30T09:05:19.917745Z","end":"2026-06-30T09:05:20.120979Z","steps":["trace[1249525608] 'agreement among raft nodes before linearized reading' (duration: 203.008059ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:05:20.121296Z","caller":"traceutil/trace.go:171","msg":"trace[942218645] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"190.987907ms","start":"2026-06-30T09:05:19.9302Z","end":"2026-06-30T09:05:20.121188Z","steps":["trace[942218645] 'process raft request' (duration: 190.234725ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:05:32.971279Z","caller":"traceutil/trace.go:171","msg":"trace[677830762] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"170.260358ms","start":"2026-06-30T09:05:32.800981Z","end":"2026-06-30T09:05:32.971241Z","steps":["trace[677830762] 'process raft request' (duration: 169.806274ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:05:36.292017Z","caller":"traceutil/trace.go:171","msg":"trace[1983473920] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"116.880236ms","start":"2026-06-30T09:05:36.175107Z","end":"2026-06-30T09:05:36.291987Z","steps":["trace[1983473920] 'process raft request' (duration: 113.426445ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:06:15.358684Z","caller":"traceutil/trace.go:171","msg":"trace[1521439493] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"178.292752ms","start":"2026-06-30T09:06:15.180367Z","end":"2026-06-30T09:06:15.358659Z","steps":["trace[1521439493] 'process raft request' (duration: 178.136527ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:06:15.707672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.673256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T09:06:15.707978Z","caller":"traceutil/trace.go:171","msg":"trace[38831159] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"513.833736ms","start":"2026-06-30T09:06:15.194113Z","end":"2026-06-30T09:06:15.707947Z","steps":["trace[38831159] 'process raft request' (duration: 405.793699ms)","trace[38831159] 'compare' (duration: 107.509032ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:06:15.708091Z","caller":"traceutil/trace.go:171","msg":"trace[535402358] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"114.580309ms","start":"2026-06-30T09:06:15.59348Z","end":"2026-06-30T09:06:15.70806Z","steps":["trace[535402358] 'process raft request' (duration: 114.29141ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:06:15.708182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:06:15.194082Z","time spent":"513.983061ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T09:07:24.992139Z","caller":"traceutil/trace.go:171","msg":"trace[948608430] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"102.562012ms","start":"2026-06-30T09:07:24.88956Z","end":"2026-06-30T09:07:24.992122Z","steps":["trace[948608430] 'process raft request' (duration: 102.133839ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:07:24.992103Z","caller":"traceutil/trace.go:171","msg":"trace[748185481] linearizableReadLoop","detail":"{readStateIndex:2886; appliedIndex:2885; }","duration":"102.216701ms","start":"2026-06-30T09:07:24.889675Z","end":"2026-06-30T09:07:24.991892Z","steps":["trace[748185481] 'read index received' (duration: 101.945953ms)","trace[748185481] 'applied index is now lower than readState.Index' (duration: 269.528µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:07:24.992441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.752346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-30T09:07:24.992629Z","caller":"traceutil/trace.go:171","msg":"trace[1550507704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2785; }","duration":"102.958153ms","start":"2026-06-30T09:07:24.889598Z","end":"2026-06-30T09:07:24.992556Z","steps":["trace[1550507704] 'agreement among raft nodes before linearized reading' (duration: 102.574862ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:07:25.043415Z","caller":"traceutil/trace.go:171","msg":"trace[1195245449] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"150.029794ms","start":"2026-06-30T09:07:24.893348Z","end":"2026-06-30T09:07:25.043377Z","steps":["trace[1195245449] 'process raft request' (duration: 126.573326ms)","trace[1195245449] 'compare' (duration: 23.230102ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:07:25.043677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.758916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-30T09:07:25.043751Z","caller":"traceutil/trace.go:171","msg":"trace[1504665055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2787; }","duration":"151.883951ms","start":"2026-06-30T09:07:24.891849Z","end":"2026-06-30T09:07:25.043733Z","steps":["trace[1504665055] 'agreement among raft nodes before linearized reading' (duration: 151.672834ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:07:25.571852Z","caller":"traceutil/trace.go:171","msg":"trace[936487680] linearizableReadLoop","detail":"{readStateIndex:2890; appliedIndex:2889; }","duration":"114.297286ms","start":"2026-06-30T09:07:25.457514Z","end":"2026-06-30T09:07:25.571811Z","steps":["trace[936487680] 'read index received' (duration: 27.814287ms)","trace[936487680] 'applied index is now lower than readState.Index' (duration: 86.480489ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:07:25.572259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.721968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-30T09:07:25.57234Z","caller":"traceutil/trace.go:171","msg":"trace[1167136751] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2788; }","duration":"114.846662ms","start":"2026-06-30T09:07:25.457474Z","end":"2026-06-30T09:07:25.57232Z","steps":["trace[1167136751] 'agreement among raft nodes before linearized reading' (duration: 114.43776ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:11:41.241517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.158898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9ea7405a-86d9-4702-b57a-aacb1af07bbb\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-06-30T09:11:41.241615Z","caller":"traceutil/trace.go:171","msg":"trace[1886208587] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9ea7405a-86d9-4702-b57a-aacb1af07bbb; range_end:; response_count:1; response_revision:5072; }","duration":"260.331513ms","start":"2026-06-30T09:11:40.98126Z","end":"2026-06-30T09:11:41.241592Z","steps":["trace[1886208587] 'range keys from in-memory index tree' (duration: 259.927171ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:11:41.241572Z","caller":"traceutil/trace.go:171","msg":"trace[1562538576] transaction","detail":"{read_only:false; response_revision:5073; number_of_response:1; }","duration":"228.531299ms","start":"2026-06-30T09:11:41.013Z","end":"2026-06-30T09:11:41.241531Z","steps":["trace[1562538576] 'process raft request' (duration: 188.125684ms)","trace[1562538576] 'compare' (duration: 40.197749ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:11:41.24157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.247455ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T09:11:41.241517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.571987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-qnvbs-tls\" ","response":"range_response_count:1 size:1967"} {"level":"info","ts":"2026-06-30T09:11:41.241781Z","caller":"traceutil/trace.go:171","msg":"trace[1021256747] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5072; }","duration":"128.455071ms","start":"2026-06-30T09:11:41.11328Z","end":"2026-06-30T09:11:41.241735Z","steps":["trace[1021256747] 'range keys from in-memory index tree' (duration: 128.163463ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:11:41.241845Z","caller":"traceutil/trace.go:171","msg":"trace[2032067617] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-qnvbs-tls; range_end:; response_count:1; response_revision:5072; }","duration":"248.972ms","start":"2026-06-30T09:11:40.99285Z","end":"2026-06-30T09:11:41.241822Z","steps":["trace[2032067617] 'range keys from in-memory index tree' (duration: 248.291859ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:11:41.306623Z","caller":"traceutil/trace.go:171","msg":"trace[842858730] linearizableReadLoop","detail":"{readStateIndex:5262; appliedIndex:5260; }","duration":"219.504714ms","start":"2026-06-30T09:11:41.087077Z","end":"2026-06-30T09:11:41.306582Z","steps":["trace[842858730] 'read index received' (duration: 114.062349ms)","trace[842858730] 'applied index is now lower than readState.Index' (duration: 105.441255ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:11:41.306661Z","caller":"traceutil/trace.go:171","msg":"trace[1311441156] transaction","detail":"{read_only:false; response_revision:5074; number_of_response:1; }","duration":"288.343164ms","start":"2026-06-30T09:11:41.018285Z","end":"2026-06-30T09:11:41.306629Z","steps":["trace[1311441156] 'process raft request' (duration: 288.127668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:11:41.306888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.782223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9ea7405a-86d9-4702-b57a-aacb1af07bbb\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-06-30T09:11:41.306931Z","caller":"traceutil/trace.go:171","msg":"trace[424073941] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9ea7405a-86d9-4702-b57a-aacb1af07bbb; range_end:; response_count:1; response_revision:5074; }","duration":"219.853015ms","start":"2026-06-30T09:11:41.087067Z","end":"2026-06-30T09:11:41.30692Z","steps":["trace[424073941] 'agreement among raft nodes before linearized reading' (duration: 219.660709ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:11:41.333577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.358345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T09:11:41.333671Z","caller":"traceutil/trace.go:171","msg":"trace[1669340925] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:5076; }","duration":"227.500829ms","start":"2026-06-30T09:11:41.106146Z","end":"2026-06-30T09:11:41.333647Z","steps":["trace[1669340925] 'agreement among raft nodes before linearized reading' (duration: 227.361565ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:11:54.023051Z","caller":"traceutil/trace.go:171","msg":"trace[1783095205] transaction","detail":"{read_only:false; response_revision:5320; number_of_response:1; }","duration":"110.450283ms","start":"2026-06-30T09:11:53.912557Z","end":"2026-06-30T09:11:54.023007Z","steps":["trace[1783095205] 'process raft request' (duration: 90.866209ms)","trace[1783095205] 'compare' (duration: 19.244975ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:11:54.570841Z","caller":"traceutil/trace.go:171","msg":"trace[373631547] linearizableReadLoop","detail":"{readStateIndex:5518; appliedIndex:5517; }","duration":"179.606133ms","start":"2026-06-30T09:11:54.391215Z","end":"2026-06-30T09:11:54.570821Z","steps":["trace[373631547] 'read index received' (duration: 179.306314ms)","trace[373631547] 'applied index is now lower than readState.Index' (duration: 299.089µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:11:54.571088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.85917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-30T09:11:54.57114Z","caller":"traceutil/trace.go:171","msg":"trace[1107122699] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5323; }","duration":"179.934453ms","start":"2026-06-30T09:11:54.391198Z","end":"2026-06-30T09:11:54.571132Z","steps":["trace[1107122699] 'agreement among raft nodes before linearized reading' (duration: 179.722016ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:11:54.57162Z","caller":"traceutil/trace.go:171","msg":"trace[655189916] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"299.236196ms","start":"2026-06-30T09:11:54.272354Z","end":"2026-06-30T09:11:54.571591Z","steps":["trace[655189916] 'process raft request' (duration: 298.307948ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:11:54.573044Z","caller":"traceutil/trace.go:171","msg":"trace[1320499287] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"181.401306ms","start":"2026-06-30T09:11:54.391602Z","end":"2026-06-30T09:11:54.573003Z","steps":["trace[1320499287] 'process raft request' (duration: 181.212591ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:12:02.837888Z","caller":"traceutil/trace.go:171","msg":"trace[128277646] linearizableReadLoop","detail":"{readStateIndex:5713; appliedIndex:5711; }","duration":"160.655026ms","start":"2026-06-30T09:12:02.677196Z","end":"2026-06-30T09:12:02.837851Z","steps":["trace[128277646] 'read index received' (duration: 19.102091ms)","trace[128277646] 'applied index is now lower than readState.Index' (duration: 141.551845ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:12:02.838114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.893142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-30T09:12:02.838142Z","caller":"traceutil/trace.go:171","msg":"trace[889436306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5513; }","duration":"160.983596ms","start":"2026-06-30T09:12:02.677151Z","end":"2026-06-30T09:12:02.838135Z","steps":["trace[889436306] 'agreement among raft nodes before linearized reading' (duration: 160.847121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:12:02.838187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.874203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-30T09:12:02.838278Z","caller":"traceutil/trace.go:171","msg":"trace[950429218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5513; }","duration":"160.968215ms","start":"2026-06-30T09:12:02.677285Z","end":"2026-06-30T09:12:02.838253Z","steps":["trace[950429218] 'agreement among raft nodes before linearized reading' (duration: 160.762848ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:12:02.838068Z","caller":"traceutil/trace.go:171","msg":"trace[71236925] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"179.333824ms","start":"2026-06-30T09:12:02.658633Z","end":"2026-06-30T09:12:02.837967Z","steps":["trace[71236925] 'process raft request' (duration: 156.406121ms)","trace[71236925] 'compare' (duration: 22.709127ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:12:02.838604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.420272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-30T09:12:02.838638Z","caller":"traceutil/trace.go:171","msg":"trace[1476404132] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5513; }","duration":"156.544175ms","start":"2026-06-30T09:12:02.682087Z","end":"2026-06-30T09:12:02.838631Z","steps":["trace[1476404132] 'agreement among raft nodes before linearized reading' (duration: 156.468533ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:12:57.337457Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2964} {"level":"info","ts":"2026-06-30T09:12:57.417356Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2964,"took":"77.411341ms","hash":1891384974,"current-db-size-bytes":59211776,"current-db-size":"59 MB","current-db-size-in-use-bytes":33832960,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-30T09:12:57.417481Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1891384974,"revision":2964,"compact-revision":-1} {"level":"info","ts":"2026-06-30T09:17:57.345109Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6012} {"level":"info","ts":"2026-06-30T09:17:57.452771Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6012,"took":"104.434386ms","hash":2726311193,"current-db-size-bytes":59211776,"current-db-size":"59 MB","current-db-size-in-use-bytes":21684224,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-30T09:17:57.452858Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2726311193,"revision":6012,"compact-revision":2964} {"level":"info","ts":"2026-06-30T09:18:23.103815Z","caller":"traceutil/trace.go:171","msg":"trace[1183696276] transaction","detail":"{read_only:false; response_revision:8389; number_of_response:1; }","duration":"243.366044ms","start":"2026-06-30T09:18:22.860414Z","end":"2026-06-30T09:18:23.10378Z","steps":["trace[1183696276] 'process raft request' (duration: 243.101746ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:18:23.283333Z","caller":"traceutil/trace.go:171","msg":"trace[2048600904] transaction","detail":"{read_only:false; response_revision:8390; number_of_response:1; }","duration":"392.442268ms","start":"2026-06-30T09:18:22.890855Z","end":"2026-06-30T09:18:23.283297Z","steps":["trace[2048600904] 'process raft request' (duration: 355.321899ms)","trace[2048600904] 'compare' (duration: 36.954325ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:18:23.283433Z","caller":"traceutil/trace.go:171","msg":"trace[831959739] linearizableReadLoop","detail":"{readStateIndex:8682; appliedIndex:8680; }","duration":"354.753262ms","start":"2026-06-30T09:18:22.928663Z","end":"2026-06-30T09:18:23.283417Z","steps":["trace[831959739] 'read index received' (duration: 174.985796ms)","trace[831959739] 'applied index is now lower than readState.Index' (duration: 179.766806ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:18:23.283516Z","caller":"traceutil/trace.go:171","msg":"trace[470536581] transaction","detail":"{read_only:false; response_revision:8391; number_of_response:1; }","duration":"297.539693ms","start":"2026-06-30T09:18:22.98597Z","end":"2026-06-30T09:18:23.283509Z","steps":["trace[470536581] 'process raft request' (duration: 297.292875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:18:23.283511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:18:22.890829Z","time spent":"392.591853ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T09:18:23.283699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.04293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-30T09:18:23.28373Z","caller":"traceutil/trace.go:171","msg":"trace[194910349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8391; }","duration":"355.119783ms","start":"2026-06-30T09:18:22.928602Z","end":"2026-06-30T09:18:23.283722Z","steps":["trace[194910349] 'agreement among raft nodes before linearized reading' (duration: 355.007029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:18:23.283746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:18:22.928581Z","time spent":"355.161453ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-30T09:18:23.283767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.757246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T09:18:23.283774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.699236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T09:18:23.283807Z","caller":"traceutil/trace.go:171","msg":"trace[1114521767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8391; }","duration":"317.846259ms","start":"2026-06-30T09:18:22.96595Z","end":"2026-06-30T09:18:23.283797Z","steps":["trace[1114521767] 'agreement among raft nodes before linearized reading' (duration: 317.786417ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:18:23.283826Z","caller":"traceutil/trace.go:171","msg":"trace[1822040118] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:8391; }","duration":"177.807808ms","start":"2026-06-30T09:18:23.106003Z","end":"2026-06-30T09:18:23.283811Z","steps":["trace[1822040118] 'agreement among raft nodes before linearized reading' (duration: 177.717686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:18:23.283839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:18:22.965924Z","time spent":"317.908241ms","remote":"127.0.0.1:58492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T09:18:23.28391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.344261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-30T09:18:23.283997Z","caller":"traceutil/trace.go:171","msg":"trace[649894323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8391; }","duration":"353.461065ms","start":"2026-06-30T09:18:22.930517Z","end":"2026-06-30T09:18:23.283978Z","steps":["trace[649894323] 'agreement among raft nodes before linearized reading' (duration: 353.202747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:18:23.283959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.864133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T09:18:23.284035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:18:22.930505Z","time spent":"353.521487ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-30T09:18:23.28391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.44189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-30T09:18:23.284081Z","caller":"traceutil/trace.go:171","msg":"trace[939569787] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:8391; }","duration":"313.049608ms","start":"2026-06-30T09:18:22.971004Z","end":"2026-06-30T09:18:23.284054Z","steps":["trace[939569787] 'agreement among raft nodes before linearized reading' (duration: 312.711868ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:18:23.284109Z","caller":"traceutil/trace.go:171","msg":"trace[1731284054] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8391; }","duration":"341.698379ms","start":"2026-06-30T09:18:22.942389Z","end":"2026-06-30T09:18:23.284087Z","steps":["trace[1731284054] 'agreement among raft nodes before linearized reading' (duration: 341.394199ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:18:23.284158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:18:22.942344Z","time spent":"341.801532ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-30T09:18:23.284149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:18:22.970981Z","time spent":"313.149092ms","remote":"127.0.0.1:58922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-06-30T09:18:35.707704Z","caller":"traceutil/trace.go:171","msg":"trace[930613013] transaction","detail":"{read_only:false; response_revision:8453; number_of_response:1; }","duration":"297.4707ms","start":"2026-06-30T09:18:35.410119Z","end":"2026-06-30T09:18:35.707589Z","steps":["trace[930613013] 'process raft request' (duration: 287.180089ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:18:35.730192Z","caller":"traceutil/trace.go:171","msg":"trace[358521599] transaction","detail":"{read_only:false; response_revision:8454; number_of_response:1; }","duration":"317.248862ms","start":"2026-06-30T09:18:35.412904Z","end":"2026-06-30T09:18:35.730153Z","steps":["trace[358521599] 'process raft request' (duration: 317.043066ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:18:35.730341Z","caller":"traceutil/trace.go:171","msg":"trace[62030485] linearizableReadLoop","detail":"{readStateIndex:8749; appliedIndex:8746; }","duration":"264.043779ms","start":"2026-06-30T09:18:35.466278Z","end":"2026-06-30T09:18:35.730321Z","steps":["trace[62030485] 'read index received' (duration: 231.041232ms)","trace[62030485] 'applied index is now lower than readState.Index' (duration: 33.000727ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:18:35.730441Z","caller":"traceutil/trace.go:171","msg":"trace[1110159607] transaction","detail":"{read_only:false; response_revision:8455; number_of_response:1; }","duration":"317.381656ms","start":"2026-06-30T09:18:35.413048Z","end":"2026-06-30T09:18:35.73043Z","steps":["trace[1110159607] 'process raft request' (duration: 317.050606ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:18:35.730486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:18:35.412892Z","time spent":"317.397616ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T09:18:35.730499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:18:35.413018Z","time spent":"317.448127ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T09:18:35.730902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.611837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lpkrb\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-06-30T09:18:35.730982Z","caller":"traceutil/trace.go:171","msg":"trace[808711168] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lpkrb; range_end:; response_count:1; response_revision:8455; }","duration":"264.744851ms","start":"2026-06-30T09:18:35.466217Z","end":"2026-06-30T09:18:35.730962Z","steps":["trace[808711168] 'agreement among raft nodes before linearized reading' (duration: 264.470903ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:18:35.766261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.93462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T09:18:35.76641Z","caller":"traceutil/trace.go:171","msg":"trace[1324531791] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:8455; }","duration":"274.126206ms","start":"2026-06-30T09:18:35.492224Z","end":"2026-06-30T09:18:35.766351Z","steps":["trace[1324531791] 'agreement among raft nodes before linearized reading' (duration: 273.894239ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:18:59.904065Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022c0.wal"} {"level":"info","ts":"2026-06-30T09:19:04.712323Z","caller":"traceutil/trace.go:171","msg":"trace[1677534713] transaction","detail":"{read_only:false; response_revision:8665; number_of_response:1; }","duration":"328.064019ms","start":"2026-06-30T09:19:04.384227Z","end":"2026-06-30T09:19:04.712291Z","steps":["trace[1677534713] 'process raft request' (duration: 327.608226ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:19:04.712523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:19:04.384203Z","time spent":"328.217063ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T09:19:04.832256Z","caller":"traceutil/trace.go:171","msg":"trace[551229114] transaction","detail":"{read_only:false; response_revision:8666; number_of_response:1; }","duration":"295.031439ms","start":"2026-06-30T09:19:04.537195Z","end":"2026-06-30T09:19:04.832227Z","steps":["trace[551229114] 'process raft request' (duration: 294.863174ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:19:18.038024Z","caller":"traceutil/trace.go:171","msg":"trace[850423392] transaction","detail":"{read_only:false; response_revision:8775; number_of_response:1; }","duration":"125.125163ms","start":"2026-06-30T09:19:17.912871Z","end":"2026-06-30T09:19:18.037996Z","steps":["trace[850423392] 'process raft request' (duration: 124.945858ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:19:18.114982Z","caller":"traceutil/trace.go:171","msg":"trace[270982373] linearizableReadLoop","detail":"{readStateIndex:9084; appliedIndex:9083; }","duration":"157.105061ms","start":"2026-06-30T09:19:17.957842Z","end":"2026-06-30T09:19:18.114947Z","steps":["trace[270982373] 'read index received' (duration: 80.156863ms)","trace[270982373] 'applied index is now lower than readState.Index' (duration: 76.946538ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:19:18.115492Z","caller":"traceutil/trace.go:171","msg":"trace[509953093] transaction","detail":"{read_only:false; response_revision:8776; number_of_response:1; }","duration":"196.552688ms","start":"2026-06-30T09:19:17.918894Z","end":"2026-06-30T09:19:18.115447Z","steps":["trace[509953093] 'process raft request' (duration: 195.841128ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:19:18.115611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.710155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T09:19:18.115683Z","caller":"traceutil/trace.go:171","msg":"trace[597702821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8776; }","duration":"149.850759ms","start":"2026-06-30T09:19:17.965816Z","end":"2026-06-30T09:19:18.115666Z","steps":["trace[597702821] 'agreement among raft nodes before linearized reading' (duration: 149.688383ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:19:18.116014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.463654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-30T09:19:18.116117Z","caller":"traceutil/trace.go:171","msg":"trace[135581683] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8776; }","duration":"122.64367ms","start":"2026-06-30T09:19:17.993449Z","end":"2026-06-30T09:19:18.116093Z","steps":["trace[135581683] 'agreement among raft nodes before linearized reading' (duration: 122.28958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:19:18.116526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.399265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-30T09:19:18.116606Z","caller":"traceutil/trace.go:171","msg":"trace[2034399620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8776; }","duration":"132.477447ms","start":"2026-06-30T09:19:17.984109Z","end":"2026-06-30T09:19:18.116587Z","steps":["trace[2034399620] 'agreement among raft nodes before linearized reading' (duration: 131.692565ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:19:18.116763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.887243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-30T09:19:18.116819Z","caller":"traceutil/trace.go:171","msg":"trace[2071910987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8776; }","duration":"159.012448ms","start":"2026-06-30T09:19:17.957794Z","end":"2026-06-30T09:19:18.116807Z","steps":["trace[2071910987] 'agreement among raft nodes before linearized reading' (duration: 157.552405ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:20:38.557287Z","caller":"traceutil/trace.go:171","msg":"trace[30208162] transaction","detail":"{read_only:false; response_revision:9553; number_of_response:1; }","duration":"274.838503ms","start":"2026-06-30T09:20:38.282418Z","end":"2026-06-30T09:20:38.557256Z","steps":["trace[30208162] 'process raft request' (duration: 274.322878ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:20:38.717802Z","caller":"traceutil/trace.go:171","msg":"trace[347269191] transaction","detail":"{read_only:false; response_revision:9556; number_of_response:1; }","duration":"102.24046ms","start":"2026-06-30T09:20:38.615526Z","end":"2026-06-30T09:20:38.717767Z","steps":["trace[347269191] 'process raft request' (duration: 74.536267ms)","trace[347269191] 'compare' (duration: 27.079414ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:20:51.838951Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ad427454a89beddc","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-30T09:20:51.843286Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-30T09:20:51.843483Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-30T09:22:57.357837Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8078} {"level":"info","ts":"2026-06-30T09:22:57.420874Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8078,"took":"61.169275ms","hash":1282194568,"current-db-size-bytes":59211776,"current-db-size":"59 MB","current-db-size-in-use-bytes":28381184,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-30T09:22:57.420949Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1282194568,"revision":8078,"compact-revision":6012} {"level":"warn","ts":"2026-06-30T09:25:57.589878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.284048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T09:25:57.590022Z","caller":"traceutil/trace.go:171","msg":"trace[641379432] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:11876; }","duration":"193.492724ms","start":"2026-06-30T09:25:57.396504Z","end":"2026-06-30T09:25:57.589996Z","steps":["trace[641379432] 'count revisions from in-memory index tree' (duration: 193.172775ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:26:21.273267Z","caller":"traceutil/trace.go:171","msg":"trace[1623515459] transaction","detail":"{read_only:false; response_revision:12004; number_of_response:1; }","duration":"156.447858ms","start":"2026-06-30T09:26:21.116786Z","end":"2026-06-30T09:26:21.273234Z","steps":["trace[1623515459] 'process raft request' (duration: 156.209221ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:26:21.424794Z","caller":"traceutil/trace.go:171","msg":"trace[599963954] transaction","detail":"{read_only:false; response_revision:12009; number_of_response:1; }","duration":"102.341131ms","start":"2026-06-30T09:26:21.322417Z","end":"2026-06-30T09:26:21.424758Z","steps":["trace[599963954] 'process raft request' (duration: 102.284789ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:26:21.424854Z","caller":"traceutil/trace.go:171","msg":"trace[39592559] transaction","detail":"{read_only:false; response_revision:12008; number_of_response:1; }","duration":"103.582357ms","start":"2026-06-30T09:26:21.321217Z","end":"2026-06-30T09:26:21.424799Z","steps":["trace[39592559] 'process raft request' (duration: 99.271681ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:27:57.363903Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10488} {"level":"info","ts":"2026-06-30T09:27:57.446642Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10488,"took":"79.573624ms","hash":3307015974,"current-db-size-bytes":59211776,"current-db-size":"59 MB","current-db-size-in-use-bytes":26312704,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-30T09:27:57.446716Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3307015974,"revision":10488,"compact-revision":8078} {"level":"info","ts":"2026-06-30T09:32:57.370807Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12649} {"level":"info","ts":"2026-06-30T09:32:57.451687Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12649,"took":"77.402447ms","hash":2180436537,"current-db-size-bytes":59211776,"current-db-size":"59 MB","current-db-size-in-use-bytes":27172864,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-30T09:32:57.451771Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2180436537,"revision":12649,"compact-revision":10488} {"level":"info","ts":"2026-06-30T09:33:02.2695Z","caller":"traceutil/trace.go:171","msg":"trace[703360094] transaction","detail":"{read_only:false; response_revision:14679; number_of_response:1; }","duration":"147.665076ms","start":"2026-06-30T09:33:02.121812Z","end":"2026-06-30T09:33:02.269477Z","steps":["trace[703360094] 'process raft request' (duration: 147.547922ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:33:11.941232Z","caller":"traceutil/trace.go:171","msg":"trace[1123795051] transaction","detail":"{read_only:false; response_revision:14853; number_of_response:1; }","duration":"144.264456ms","start":"2026-06-30T09:33:11.796946Z","end":"2026-06-30T09:33:11.94121Z","steps":["trace[1123795051] 'process raft request' (duration: 144.123322ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:33:12.190699Z","caller":"traceutil/trace.go:171","msg":"trace[1973142623] linearizableReadLoop","detail":"{readStateIndex:15361; appliedIndex:15360; }","duration":"226.155216ms","start":"2026-06-30T09:33:11.964513Z","end":"2026-06-30T09:33:12.190669Z","steps":["trace[1973142623] 'read index received' (duration: 225.912318ms)","trace[1973142623] 'applied index is now lower than readState.Index' (duration: 241.918µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:33:12.190728Z","caller":"traceutil/trace.go:171","msg":"trace[1666841448] transaction","detail":"{read_only:false; response_revision:14854; number_of_response:1; }","duration":"237.583921ms","start":"2026-06-30T09:33:11.953118Z","end":"2026-06-30T09:33:12.190702Z","steps":["trace[1666841448] 'process raft request' (duration: 237.332902ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:33:12.19086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.332991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T09:33:12.190923Z","caller":"traceutil/trace.go:171","msg":"trace[1159504653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14854; }","duration":"226.430393ms","start":"2026-06-30T09:33:11.964471Z","end":"2026-06-30T09:33:12.190902Z","steps":["trace[1159504653] 'agreement among raft nodes before linearized reading' (duration: 226.32521ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:34:37.531424Z","caller":"traceutil/trace.go:171","msg":"trace[1058597171] linearizableReadLoop","detail":"{readStateIndex:16397; appliedIndex:16396; }","duration":"164.062816ms","start":"2026-06-30T09:34:37.367332Z","end":"2026-06-30T09:34:37.531395Z","steps":["trace[1058597171] 'read index received' (duration: 163.913852ms)","trace[1058597171] 'applied index is now lower than readState.Index' (duration: 147.544µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:34:37.53153Z","caller":"traceutil/trace.go:171","msg":"trace[511585564] transaction","detail":"{read_only:false; response_revision:15860; number_of_response:1; }","duration":"182.404894ms","start":"2026-06-30T09:34:37.349081Z","end":"2026-06-30T09:34:37.531485Z","steps":["trace[511585564] 'process raft request' (duration: 182.145406ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:34:37.531798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.399395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-06-30T09:34:37.532437Z","caller":"traceutil/trace.go:171","msg":"trace[870269657] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15860; }","duration":"165.132208ms","start":"2026-06-30T09:34:37.367283Z","end":"2026-06-30T09:34:37.532415Z","steps":["trace[870269657] 'agreement among raft nodes before linearized reading' (duration: 164.22779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:34:37.532506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.897771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-6cq2n\" ","response":"range_response_count:1 size:22014"} {"level":"info","ts":"2026-06-30T09:34:37.53262Z","caller":"traceutil/trace.go:171","msg":"trace[1291619554] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-6cq2n; range_end:; response_count:1; response_revision:15861; }","duration":"122.012885ms","start":"2026-06-30T09:34:37.410543Z","end":"2026-06-30T09:34:37.532556Z","steps":["trace[1291619554] 'agreement among raft nodes before linearized reading' (duration: 121.789268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:34:37.533046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.705227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-6cq2n\" ","response":"range_response_count:1 size:22014"} {"level":"info","ts":"2026-06-30T09:34:37.533076Z","caller":"traceutil/trace.go:171","msg":"trace[577397883] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-6cq2n; range_end:; response_count:1; response_revision:15861; }","duration":"121.759717ms","start":"2026-06-30T09:34:37.411309Z","end":"2026-06-30T09:34:37.533069Z","steps":["trace[577397883] 'agreement among raft nodes before linearized reading' (duration: 121.682275ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:34:37.646432Z","caller":"traceutil/trace.go:171","msg":"trace[1069673766] transaction","detail":"{read_only:false; response_revision:15863; number_of_response:1; }","duration":"109.867088ms","start":"2026-06-30T09:34:37.536539Z","end":"2026-06-30T09:34:37.646406Z","steps":["trace[1069673766] 'process raft request' (duration: 109.631361ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:34:37.646543Z","caller":"traceutil/trace.go:171","msg":"trace[1155583191] linearizableReadLoop","detail":"{readStateIndex:16402; appliedIndex:16398; }","duration":"106.13922ms","start":"2026-06-30T09:34:37.540384Z","end":"2026-06-30T09:34:37.646523Z","steps":["trace[1155583191] 'read index received' (duration: 14.811784ms)","trace[1155583191] 'applied index is now lower than readState.Index' (duration: 91.326026ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:34:37.646433Z","caller":"traceutil/trace.go:171","msg":"trace[30635697] transaction","detail":"{read_only:false; response_revision:15864; number_of_response:1; }","duration":"109.377324ms","start":"2026-06-30T09:34:37.537033Z","end":"2026-06-30T09:34:37.64641Z","steps":["trace[30635697] 'process raft request' (duration: 109.278911ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:34:37.646622Z","caller":"traceutil/trace.go:171","msg":"trace[1366082343] transaction","detail":"{read_only:false; response_revision:15865; number_of_response:1; }","duration":"109.204649ms","start":"2026-06-30T09:34:37.537409Z","end":"2026-06-30T09:34:37.646613Z","steps":["trace[1366082343] 'process raft request' (duration: 108.942812ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:34:37.647677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.274083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:764223"} {"level":"info","ts":"2026-06-30T09:34:37.647781Z","caller":"traceutil/trace.go:171","msg":"trace[1858510940] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15865; }","duration":"107.411007ms","start":"2026-06-30T09:34:37.540346Z","end":"2026-06-30T09:34:37.647757Z","steps":["trace[1858510940] 'agreement among raft nodes before linearized reading' (duration: 106.299194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:34:37.648177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.750418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:764223"} {"level":"info","ts":"2026-06-30T09:34:37.64826Z","caller":"traceutil/trace.go:171","msg":"trace[1911437677] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15865; }","duration":"107.86989ms","start":"2026-06-30T09:34:37.540371Z","end":"2026-06-30T09:34:37.64824Z","steps":["trace[1911437677] 'agreement among raft nodes before linearized reading' (duration: 106.626593ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:37:57.404605Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14640} {"level":"info","ts":"2026-06-30T09:37:57.532278Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14640,"took":"123.551284ms","hash":1575863012,"current-db-size-bytes":59211776,"current-db-size":"59 MB","current-db-size-in-use-bytes":33910784,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-30T09:37:57.53236Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1575863012,"revision":14640,"compact-revision":12649} {"level":"info","ts":"2026-06-30T09:40:29.028826Z","caller":"traceutil/trace.go:171","msg":"trace[2146952020] transaction","detail":"{read_only:false; response_revision:18420; number_of_response:1; }","duration":"106.898356ms","start":"2026-06-30T09:40:28.921899Z","end":"2026-06-30T09:40:29.028797Z","steps":["trace[2146952020] 'process raft request' (duration: 106.740892ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:40:29.096701Z","caller":"traceutil/trace.go:171","msg":"trace[1648763383] linearizableReadLoop","detail":"{readStateIndex:19045; appliedIndex:19043; }","duration":"129.975623ms","start":"2026-06-30T09:40:28.966661Z","end":"2026-06-30T09:40:29.096637Z","steps":["trace[1648763383] 'read index received' (duration: 62.063226ms)","trace[1648763383] 'applied index is now lower than readState.Index' (duration: 67.911207ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:40:29.096783Z","caller":"traceutil/trace.go:171","msg":"trace[908175693] transaction","detail":"{read_only:false; response_revision:18421; number_of_response:1; }","duration":"166.316015ms","start":"2026-06-30T09:40:28.930402Z","end":"2026-06-30T09:40:29.096718Z","steps":["trace[908175693] 'process raft request' (duration: 165.960825ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:40:29.097087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.194159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:40:29.097134Z","caller":"traceutil/trace.go:171","msg":"trace[795754205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18422; }","duration":"130.55016ms","start":"2026-06-30T09:40:28.96657Z","end":"2026-06-30T09:40:29.09712Z","steps":["trace[795754205] 'agreement among raft nodes before linearized reading' (duration: 130.22734ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:40:35.716201Z","caller":"traceutil/trace.go:171","msg":"trace[1735422101] transaction","detail":"{read_only:false; response_revision:18482; number_of_response:1; }","duration":"111.015938ms","start":"2026-06-30T09:40:35.605143Z","end":"2026-06-30T09:40:35.716159Z","steps":["trace[1735422101] 'process raft request' (duration: 110.7593ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:40:35.997584Z","caller":"traceutil/trace.go:171","msg":"trace[2040647465] transaction","detail":"{read_only:false; response_revision:18484; number_of_response:1; }","duration":"273.362506ms","start":"2026-06-30T09:40:35.724195Z","end":"2026-06-30T09:40:35.997558Z","steps":["trace[2040647465] 'process raft request' (duration: 273.133279ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:40:35.998022Z","caller":"traceutil/trace.go:171","msg":"trace[753455059] transaction","detail":"{read_only:false; response_revision:18485; number_of_response:1; }","duration":"273.669135ms","start":"2026-06-30T09:40:35.724339Z","end":"2026-06-30T09:40:35.998008Z","steps":["trace[753455059] 'process raft request' (duration: 273.1679ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:40:35.998206Z","caller":"traceutil/trace.go:171","msg":"trace[56741981] linearizableReadLoop","detail":"{readStateIndex:19110; appliedIndex:19107; }","duration":"270.537113ms","start":"2026-06-30T09:40:35.727595Z","end":"2026-06-30T09:40:35.998132Z","steps":["trace[56741981] 'read index received' (duration: 13.006601ms)","trace[56741981] 'applied index is now lower than readState.Index' (duration: 257.529142ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:40:35.998421Z","caller":"traceutil/trace.go:171","msg":"trace[25064847] transaction","detail":"{read_only:false; response_revision:18486; number_of_response:1; }","duration":"265.055904ms","start":"2026-06-30T09:40:35.733242Z","end":"2026-06-30T09:40:35.998298Z","steps":["trace[25064847] 'process raft request' (duration: 264.59072ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:40:35.998635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.003268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:1647"} {"level":"info","ts":"2026-06-30T09:40:35.998729Z","caller":"traceutil/trace.go:171","msg":"trace[755767656] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:18486; }","duration":"271.133282ms","start":"2026-06-30T09:40:35.727575Z","end":"2026-06-30T09:40:35.998708Z","steps":["trace[755767656] 'agreement among raft nodes before linearized reading' (duration: 270.867244ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:40:36.206462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.296766ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-06-30T09:40:36.206758Z","caller":"traceutil/trace.go:171","msg":"trace[1600790437] linearizableReadLoop","detail":"{readStateIndex:19112; appliedIndex:19111; }","duration":"208.328334ms","start":"2026-06-30T09:40:35.99841Z","end":"2026-06-30T09:40:36.206738Z","steps":["trace[1600790437] 'read index received' (duration: 29.780831ms)","trace[1600790437] 'applied index is now lower than readState.Index' (duration: 178.546363ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:40:36.207049Z","caller":"traceutil/trace.go:171","msg":"trace[1680728363] transaction","detail":"{read_only:false; response_revision:18487; number_of_response:1; }","duration":"200.055122ms","start":"2026-06-30T09:40:36.006952Z","end":"2026-06-30T09:40:36.207007Z","steps":["trace[1680728363] 'process raft request' (duration: 199.63932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:40:36.207936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.831305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-30T09:40:36.208032Z","caller":"traceutil/trace.go:171","msg":"trace[1583536284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18486; }","duration":"348.873525ms","start":"2026-06-30T09:40:35.859138Z","end":"2026-06-30T09:40:36.208011Z","steps":["trace[1583536284] 'agreement among raft nodes before linearized reading' (duration: 347.66599ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:40:36.208094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:40:35.859112Z","time spent":"348.964868ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-30T09:40:36.234348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.190665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:40:36.234477Z","caller":"traceutil/trace.go:171","msg":"trace[1736033289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18487; }","duration":"270.37533ms","start":"2026-06-30T09:40:35.964076Z","end":"2026-06-30T09:40:36.234451Z","steps":["trace[1736033289] 'agreement among raft nodes before linearized reading' (duration: 242.992539ms)","trace[1736033289] 'range keys from in-memory index tree' (duration: 27.183095ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:40:36.249426Z","caller":"traceutil/trace.go:171","msg":"trace[292423330] transaction","detail":"{read_only:false; response_revision:18488; number_of_response:1; }","duration":"100.733736ms","start":"2026-06-30T09:40:36.148655Z","end":"2026-06-30T09:40:36.249389Z","steps":["trace[292423330] 'process raft request' (duration: 100.52855ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:41:55.411578Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ad427454a89beddc","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-30T09:41:55.418654Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-30T09:41:55.418811Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-30T09:42:57.411095Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17395} {"level":"info","ts":"2026-06-30T09:42:57.52195Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17395,"took":"105.97705ms","hash":2425505991,"current-db-size-bytes":59211776,"current-db-size":"59 MB","current-db-size-in-use-bytes":35000320,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-30T09:42:57.522016Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2425505991,"revision":17395,"compact-revision":14640} {"level":"info","ts":"2026-06-30T09:46:25.823048Z","caller":"traceutil/trace.go:171","msg":"trace[1617743575] transaction","detail":"{read_only:false; response_revision:21181; number_of_response:1; }","duration":"131.120557ms","start":"2026-06-30T09:46:25.691903Z","end":"2026-06-30T09:46:25.823023Z","steps":["trace[1617743575] 'process raft request' (duration: 130.995453ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:46:32.104553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.154772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T09:46:32.104818Z","caller":"traceutil/trace.go:171","msg":"trace[1392616104] linearizableReadLoop","detail":"{readStateIndex:21929; appliedIndex:21927; }","duration":"140.713488ms","start":"2026-06-30T09:46:31.964081Z","end":"2026-06-30T09:46:32.104795Z","steps":["trace[1392616104] 'read index received' (duration: 41.432933ms)","trace[1392616104] 'applied index is now lower than readState.Index' (duration: 99.279535ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:46:32.10486Z","caller":"traceutil/trace.go:171","msg":"trace[2049501775] transaction","detail":"{read_only:false; response_revision:21211; number_of_response:1; }","duration":"258.144894ms","start":"2026-06-30T09:46:31.846642Z","end":"2026-06-30T09:46:32.104787Z","steps":["trace[2049501775] 'process raft request' (duration: 104.645722ms)","trace[2049501775] 'compare' (duration: 152.990098ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:46:32.104935Z","caller":"traceutil/trace.go:171","msg":"trace[1454286067] transaction","detail":"{read_only:false; response_revision:21212; number_of_response:1; }","duration":"256.077163ms","start":"2026-06-30T09:46:31.848844Z","end":"2026-06-30T09:46:32.104921Z","steps":["trace[1454286067] 'process raft request' (duration: 255.832456ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:46:32.105331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.253363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:46:32.10539Z","caller":"traceutil/trace.go:171","msg":"trace[616703559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21212; }","duration":"141.351596ms","start":"2026-06-30T09:46:31.964024Z","end":"2026-06-30T09:46:32.105376Z","steps":["trace[616703559] 'agreement among raft nodes before linearized reading' (duration: 141.255163ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:46:32.275696Z","caller":"traceutil/trace.go:171","msg":"trace[1242660237] linearizableReadLoop","detail":"{readStateIndex:21931; appliedIndex:21929; }","duration":"140.916883ms","start":"2026-06-30T09:46:32.134754Z","end":"2026-06-30T09:46:32.275671Z","steps":["trace[1242660237] 'read index received' (duration: 50.207399ms)","trace[1242660237] 'applied index is now lower than readState.Index' (duration: 90.708814ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:46:32.275915Z","caller":"traceutil/trace.go:171","msg":"trace[1802011287] transaction","detail":"{read_only:false; response_revision:21214; number_of_response:1; }","duration":"163.968958ms","start":"2026-06-30T09:46:32.111935Z","end":"2026-06-30T09:46:32.275904Z","steps":["trace[1802011287] 'process raft request' (duration: 158.751735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:46:32.27616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.379557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-30T09:46:32.276247Z","caller":"traceutil/trace.go:171","msg":"trace[2039041437] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21214; }","duration":"141.520441ms","start":"2026-06-30T09:46:32.134708Z","end":"2026-06-30T09:46:32.276228Z","steps":["trace[2039041437] 'agreement among raft nodes before linearized reading' (duration: 141.258874ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:46:32.524863Z","caller":"traceutil/trace.go:171","msg":"trace[1061902282] transaction","detail":"{read_only:false; response_revision:21216; number_of_response:1; }","duration":"132.346532ms","start":"2026-06-30T09:46:32.39249Z","end":"2026-06-30T09:46:32.524836Z","steps":["trace[1061902282] 'process raft request' (duration: 129.860159ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:46:32.525294Z","caller":"traceutil/trace.go:171","msg":"trace[1174475758] transaction","detail":"{read_only:false; response_revision:21218; number_of_response:1; }","duration":"130.511048ms","start":"2026-06-30T09:46:32.39477Z","end":"2026-06-30T09:46:32.525281Z","steps":["trace[1174475758] 'process raft request' (duration: 130.017034ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:46:32.525391Z","caller":"traceutil/trace.go:171","msg":"trace[758428003] transaction","detail":"{read_only:false; response_revision:21217; number_of_response:1; }","duration":"132.005733ms","start":"2026-06-30T09:46:32.393378Z","end":"2026-06-30T09:46:32.525384Z","steps":["trace[758428003] 'process raft request' (duration: 131.274382ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:47:57.42153Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19756} {"level":"info","ts":"2026-06-30T09:47:57.536456Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19756,"took":"111.249355ms","hash":638114191,"current-db-size-bytes":59211776,"current-db-size":"59 MB","current-db-size-in-use-bytes":30027776,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-30T09:47:57.536521Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":638114191,"revision":19756,"compact-revision":17395} {"level":"warn","ts":"2026-06-30T09:50:30.142416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.727891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:50:30.142493Z","caller":"traceutil/trace.go:171","msg":"trace[1114653529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23696; }","duration":"175.894207ms","start":"2026-06-30T09:50:29.966583Z","end":"2026-06-30T09:50:30.142478Z","steps":["trace[1114653529] 'range keys from in-memory index tree' (duration: 175.616527ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:52:57.428488Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21978} {"level":"info","ts":"2026-06-30T09:52:57.540462Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21978,"took":"108.110943ms","hash":3923808358,"current-db-size-bytes":59211776,"current-db-size":"59 MB","current-db-size-in-use-bytes":44732416,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-06-30T09:52:57.540575Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3923808358,"revision":21978,"compact-revision":19756} {"level":"info","ts":"2026-06-30T09:53:59.398219Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006797.wal"} {"level":"info","ts":"2026-06-30T09:54:10.445925Z","caller":"traceutil/trace.go:171","msg":"trace[1329115354] linearizableReadLoop","detail":"{readStateIndex:26698; appliedIndex:26697; }","duration":"115.036412ms","start":"2026-06-30T09:54:10.330868Z","end":"2026-06-30T09:54:10.445904Z","steps":["trace[1329115354] 'read index received' (duration: 114.986391ms)","trace[1329115354] 'applied index is now lower than readState.Index' (duration: 49.381µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:10.445987Z","caller":"traceutil/trace.go:171","msg":"trace[1528819978] transaction","detail":"{read_only:false; response_revision:25851; number_of_response:1; }","duration":"121.249053ms","start":"2026-06-30T09:54:10.324689Z","end":"2026-06-30T09:54:10.445938Z","steps":["trace[1528819978] 'process raft request' (duration: 121.078418ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:10.446069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.186706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:10.446097Z","caller":"traceutil/trace.go:171","msg":"trace[897320835] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:25851; }","duration":"115.262459ms","start":"2026-06-30T09:54:10.330826Z","end":"2026-06-30T09:54:10.446088Z","steps":["trace[897320835] 'agreement among raft nodes before linearized reading' (duration: 115.164136ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:15.068328Z","caller":"traceutil/trace.go:171","msg":"trace[646557411] transaction","detail":"{read_only:false; response_revision:25893; number_of_response:1; }","duration":"137.441397ms","start":"2026-06-30T09:54:14.930844Z","end":"2026-06-30T09:54:15.068285Z","steps":["trace[646557411] 'process raft request' (duration: 121.688258ms)","trace[646557411] 'compare' (duration: 15.581194ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:16.01548Z","caller":"traceutil/trace.go:171","msg":"trace[1923006794] transaction","detail":"{read_only:false; response_revision:25895; number_of_response:1; }","duration":"147.84053ms","start":"2026-06-30T09:54:15.867612Z","end":"2026-06-30T09:54:16.015453Z","steps":["trace[1923006794] 'process raft request' (duration: 147.646264ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:16.435969Z","caller":"traceutil/trace.go:171","msg":"trace[1211193996] transaction","detail":"{read_only:false; response_revision:25898; number_of_response:1; }","duration":"131.265205ms","start":"2026-06-30T09:54:16.304679Z","end":"2026-06-30T09:54:16.435944Z","steps":["trace[1211193996] 'process raft request' (duration: 131.213604ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:16.436634Z","caller":"traceutil/trace.go:171","msg":"trace[324916891] transaction","detail":"{read_only:false; response_revision:25896; number_of_response:1; }","duration":"440.119193ms","start":"2026-06-30T09:54:15.996479Z","end":"2026-06-30T09:54:16.436598Z","steps":["trace[324916891] 'process raft request' (duration: 439.216596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:16.436826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:15.996452Z","time spent":"440.266457ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T09:54:16.437132Z","caller":"traceutil/trace.go:171","msg":"trace[2119797710] linearizableReadLoop","detail":"{readStateIndex:26744; appliedIndex:26742; }","duration":"500.053595ms","start":"2026-06-30T09:54:15.937061Z","end":"2026-06-30T09:54:16.437114Z","steps":["trace[2119797710] 'read index received' (duration: 78.290558ms)","trace[2119797710] 'applied index is now lower than readState.Index' (duration: 421.760597ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:16.437244Z","caller":"traceutil/trace.go:171","msg":"trace[1111993186] transaction","detail":"{read_only:false; response_revision:25897; number_of_response:1; }","duration":"198.589584ms","start":"2026-06-30T09:54:16.238641Z","end":"2026-06-30T09:54:16.437231Z","steps":["trace[1111993186] 'process raft request' (duration: 197.203123ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:16.43773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.141216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-30T09:54:16.437725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.45934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:16.437796Z","caller":"traceutil/trace.go:171","msg":"trace[1584603918] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:25898; }","duration":"243.238749ms","start":"2026-06-30T09:54:16.194541Z","end":"2026-06-30T09:54:16.437779Z","steps":["trace[1584603918] 'agreement among raft nodes before linearized reading' (duration: 243.085345ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:16.437846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.013815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-30T09:54:16.437846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.772085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:16.437891Z","caller":"traceutil/trace.go:171","msg":"trace[832273977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25898; }","duration":"473.188949ms","start":"2026-06-30T09:54:15.964692Z","end":"2026-06-30T09:54:16.437881Z","steps":["trace[832273977] 'agreement among raft nodes before linearized reading' (duration: 472.792488ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:16.437932Z","caller":"traceutil/trace.go:171","msg":"trace[1657459229] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25898; }","duration":"500.860518ms","start":"2026-06-30T09:54:15.937051Z","end":"2026-06-30T09:54:16.437912Z","steps":["trace[1657459229] 'agreement among raft nodes before linearized reading' (duration: 500.408055ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:16.437944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:15.964661Z","time spent":"473.273972ms","remote":"127.0.0.1:58492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T09:54:16.437985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:15.936979Z","time spent":"500.991442ms","remote":"127.0.0.1:38614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-06-30T09:54:16.438186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.729179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T09:54:16.438247Z","caller":"traceutil/trace.go:171","msg":"trace[755597859] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:25898; }","duration":"325.845312ms","start":"2026-06-30T09:54:16.112388Z","end":"2026-06-30T09:54:16.438233Z","steps":["trace[755597859] 'agreement among raft nodes before linearized reading' (duration: 325.626586ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:16.438298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:16.112365Z","time spent":"325.916435ms","remote":"127.0.0.1:58880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":99,"response size":32,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-06-30T09:54:16.437805Z","caller":"traceutil/trace.go:171","msg":"trace[1659225618] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:25898; }","duration":"210.597614ms","start":"2026-06-30T09:54:16.227192Z","end":"2026-06-30T09:54:16.437789Z","steps":["trace[1659225618] 'agreement among raft nodes before linearized reading' (duration: 210.418859ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:16.438724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.757642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-30T09:54:16.438782Z","caller":"traceutil/trace.go:171","msg":"trace[1104161228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25898; }","duration":"335.871346ms","start":"2026-06-30T09:54:16.102896Z","end":"2026-06-30T09:54:16.438768Z","steps":["trace[1104161228] 'agreement among raft nodes before linearized reading' (duration: 335.68357ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:16.438832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:16.10287Z","time spent":"335.949907ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-30T09:54:16.581069Z","caller":"traceutil/trace.go:171","msg":"trace[1367483166] transaction","detail":"{read_only:false; response_revision:25899; number_of_response:1; }","duration":"133.12619ms","start":"2026-06-30T09:54:16.447917Z","end":"2026-06-30T09:54:16.581043Z","steps":["trace[1367483166] 'process raft request' (duration: 132.956995ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:16.599079Z","caller":"traceutil/trace.go:171","msg":"trace[1622210287] transaction","detail":"{read_only:false; response_revision:25901; number_of_response:1; }","duration":"132.513984ms","start":"2026-06-30T09:54:16.466528Z","end":"2026-06-30T09:54:16.599042Z","steps":["trace[1622210287] 'process raft request' (duration: 132.442831ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:16.599174Z","caller":"traceutil/trace.go:171","msg":"trace[1490690728] transaction","detail":"{read_only:false; response_revision:25900; number_of_response:1; }","duration":"134.571713ms","start":"2026-06-30T09:54:16.464558Z","end":"2026-06-30T09:54:16.59913Z","steps":["trace[1490690728] 'process raft request' (duration: 134.197562ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:18.997259Z","caller":"traceutil/trace.go:171","msg":"trace[916741169] transaction","detail":"{read_only:false; response_revision:25920; number_of_response:1; }","duration":"127.639951ms","start":"2026-06-30T09:54:18.869592Z","end":"2026-06-30T09:54:18.997232Z","steps":["trace[916741169] 'process raft request' (duration: 127.423594ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:19.146983Z","caller":"traceutil/trace.go:171","msg":"trace[2050158436] linearizableReadLoop","detail":"{readStateIndex:26770; appliedIndex:26768; }","duration":"181.911867ms","start":"2026-06-30T09:54:18.965038Z","end":"2026-06-30T09:54:19.14695Z","steps":["trace[2050158436] 'read index received' (duration: 31.949195ms)","trace[2050158436] 'applied index is now lower than readState.Index' (duration: 149.961222ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:19.147159Z","caller":"traceutil/trace.go:171","msg":"trace[756192111] transaction","detail":"{read_only:false; response_revision:25921; number_of_response:1; }","duration":"208.73227ms","start":"2026-06-30T09:54:18.938398Z","end":"2026-06-30T09:54:19.14713Z","steps":["trace[756192111] 'process raft request' (duration: 195.05434ms)","trace[756192111] 'compare' (duration: 13.346791ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:54:19.147244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.177704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:19.147301Z","caller":"traceutil/trace.go:171","msg":"trace[1464628457] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25921; }","duration":"182.285408ms","start":"2026-06-30T09:54:18.964996Z","end":"2026-06-30T09:54:19.147281Z","steps":["trace[1464628457] 'agreement among raft nodes before linearized reading' (duration: 182.140473ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:20.599297Z","caller":"traceutil/trace.go:171","msg":"trace[473953847] transaction","detail":"{read_only:false; response_revision:25926; number_of_response:1; }","duration":"139.608921ms","start":"2026-06-30T09:54:20.459654Z","end":"2026-06-30T09:54:20.599262Z","steps":["trace[473953847] 'process raft request' (duration: 133.406519ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:20.599452Z","caller":"traceutil/trace.go:171","msg":"trace[376715080] transaction","detail":"{read_only:false; response_revision:25927; number_of_response:1; }","duration":"139.544728ms","start":"2026-06-30T09:54:20.459873Z","end":"2026-06-30T09:54:20.599418Z","steps":["trace[376715080] 'process raft request' (duration: 139.328252ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:20.764743Z","caller":"traceutil/trace.go:171","msg":"trace[1475290326] transaction","detail":"{read_only:false; response_revision:25931; number_of_response:1; }","duration":"142.779683ms","start":"2026-06-30T09:54:20.621919Z","end":"2026-06-30T09:54:20.764698Z","steps":["trace[1475290326] 'process raft request' (duration: 142.66513ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:20.76481Z","caller":"traceutil/trace.go:171","msg":"trace[114243420] transaction","detail":"{read_only:false; response_revision:25929; number_of_response:1; }","duration":"153.229119ms","start":"2026-06-30T09:54:20.611533Z","end":"2026-06-30T09:54:20.764762Z","steps":["trace[114243420] 'process raft request' (duration: 152.958781ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:20.765112Z","caller":"traceutil/trace.go:171","msg":"trace[1250524289] transaction","detail":"{read_only:false; response_revision:25928; number_of_response:1; }","duration":"159.740088ms","start":"2026-06-30T09:54:20.605325Z","end":"2026-06-30T09:54:20.765065Z","steps":["trace[1250524289] 'process raft request' (duration: 149.272232ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:20.765104Z","caller":"traceutil/trace.go:171","msg":"trace[127374874] transaction","detail":"{read_only:false; response_revision:25930; number_of_response:1; }","duration":"151.938831ms","start":"2026-06-30T09:54:20.613135Z","end":"2026-06-30T09:54:20.765074Z","steps":["trace[127374874] 'process raft request' (duration: 151.405885ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:20.87264Z","caller":"traceutil/trace.go:171","msg":"trace[93009630] transaction","detail":"{read_only:false; response_revision:25933; number_of_response:1; }","duration":"102.598938ms","start":"2026-06-30T09:54:20.770015Z","end":"2026-06-30T09:54:20.872614Z","steps":["trace[93009630] 'process raft request' (duration: 102.052352ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:20.872914Z","caller":"traceutil/trace.go:171","msg":"trace[289151144] transaction","detail":"{read_only:false; response_revision:25934; number_of_response:1; }","duration":"102.794963ms","start":"2026-06-30T09:54:20.770095Z","end":"2026-06-30T09:54:20.87289Z","steps":["trace[289151144] 'process raft request' (duration: 102.300499ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:24.910452Z","caller":"traceutil/trace.go:171","msg":"trace[1933100881] linearizableReadLoop","detail":"{readStateIndex:26809; appliedIndex:26808; }","duration":"130.109703ms","start":"2026-06-30T09:54:24.780251Z","end":"2026-06-30T09:54:24.910361Z","steps":["trace[1933100881] 'read index received' (duration: 42.852122ms)","trace[1933100881] 'applied index is now lower than readState.Index' (duration: 87.256151ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:24.910608Z","caller":"traceutil/trace.go:171","msg":"trace[924705263] transaction","detail":"{read_only:false; response_revision:25959; number_of_response:1; }","duration":"132.39395ms","start":"2026-06-30T09:54:24.77817Z","end":"2026-06-30T09:54:24.910564Z","steps":["trace[924705263] 'process raft request' (duration: 44.913293ms)","trace[924705263] 'compare' (duration: 86.721054ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:54:24.910703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.440802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-30T09:54:24.910745Z","caller":"traceutil/trace.go:171","msg":"trace[1851024742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25959; }","duration":"130.549806ms","start":"2026-06-30T09:54:24.780184Z","end":"2026-06-30T09:54:24.910734Z","steps":["trace[1851024742] 'agreement among raft nodes before linearized reading' (duration: 130.388991ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:24.999837Z","caller":"traceutil/trace.go:171","msg":"trace[1864336588] transaction","detail":"{read_only:false; response_revision:25960; number_of_response:1; }","duration":"217.790784ms","start":"2026-06-30T09:54:24.782007Z","end":"2026-06-30T09:54:24.999798Z","steps":["trace[1864336588] 'process raft request' (duration: 217.551897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:24.999928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.100897ms","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-06-30T09:54:25.000005Z","caller":"traceutil/trace.go:171","msg":"trace[1913325561] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25960; }","duration":"112.235791ms","start":"2026-06-30T09:54:24.887747Z","end":"2026-06-30T09:54:24.999983Z","steps":["trace[1913325561] 'agreement among raft nodes before linearized reading' (duration: 112.054454ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:25.000082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.827057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-30T09:54:25.000173Z","caller":"traceutil/trace.go:171","msg":"trace[972907600] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25960; }","duration":"114.000312ms","start":"2026-06-30T09:54:24.886153Z","end":"2026-06-30T09:54:25.000153Z","steps":["trace[972907600] 'agreement among raft nodes before linearized reading' (duration: 113.688542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:25.000272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.304624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-30T09:54:25.000305Z","caller":"traceutil/trace.go:171","msg":"trace[773611188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25960; }","duration":"214.396116ms","start":"2026-06-30T09:54:24.7859Z","end":"2026-06-30T09:54:25.000297Z","steps":["trace[773611188] 'agreement among raft nodes before linearized reading' (duration: 213.829808ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:25.156273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.419056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T09:54:25.156527Z","caller":"traceutil/trace.go:171","msg":"trace[729292582] linearizableReadLoop","detail":"{readStateIndex:26814; appliedIndex:26810; }","duration":"129.397921ms","start":"2026-06-30T09:54:25.027112Z","end":"2026-06-30T09:54:25.15651Z","steps":["trace[729292582] 'read index received' (duration: 117.36048ms)","trace[729292582] 'applied index is now lower than readState.Index' (duration: 12.036301ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:54:25.156615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.499004ms","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-06-30T09:54:25.156653Z","caller":"traceutil/trace.go:171","msg":"trace[1944878911] 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:25964; }","duration":"129.590427ms","start":"2026-06-30T09:54:25.02705Z","end":"2026-06-30T09:54:25.156641Z","steps":["trace[1944878911] 'agreement among raft nodes before linearized reading' (duration: 129.529365ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:25.156673Z","caller":"traceutil/trace.go:171","msg":"trace[1412933488] transaction","detail":"{read_only:false; response_revision:25961; number_of_response:1; }","duration":"241.816497ms","start":"2026-06-30T09:54:24.914827Z","end":"2026-06-30T09:54:25.156643Z","steps":["trace[1412933488] 'process raft request' (duration: 104.946648ms)","trace[1412933488] 'compare' (duration: 136.314263ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:25.156853Z","caller":"traceutil/trace.go:171","msg":"trace[1626499088] transaction","detail":"{read_only:false; response_revision:25962; number_of_response:1; }","duration":"152.097926ms","start":"2026-06-30T09:54:25.004744Z","end":"2026-06-30T09:54:25.156842Z","steps":["trace[1626499088] 'process raft request' (duration: 151.633432ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:25.156962Z","caller":"traceutil/trace.go:171","msg":"trace[1073730266] transaction","detail":"{read_only:false; response_revision:25964; number_of_response:1; }","duration":"149.85433ms","start":"2026-06-30T09:54:25.007049Z","end":"2026-06-30T09:54:25.156904Z","steps":["trace[1073730266] 'process raft request' (duration: 149.417806ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:25.15704Z","caller":"traceutil/trace.go:171","msg":"trace[1658614273] transaction","detail":"{read_only:false; response_revision:25963; number_of_response:1; }","duration":"151.451946ms","start":"2026-06-30T09:54:25.005554Z","end":"2026-06-30T09:54:25.157006Z","steps":["trace[1658614273] 'process raft request' (duration: 150.878498ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:25.409114Z","caller":"traceutil/trace.go:171","msg":"trace[330498292] transaction","detail":"{read_only:false; response_revision:25966; number_of_response:1; }","duration":"171.807322ms","start":"2026-06-30T09:54:25.237276Z","end":"2026-06-30T09:54:25.409083Z","steps":["trace[330498292] 'process raft request' (duration: 154.544887ms)","trace[330498292] 'compare' (duration: 17.139711ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:25.69819Z","caller":"traceutil/trace.go:171","msg":"trace[1189335849] transaction","detail":"{read_only:false; response_revision:25967; number_of_response:1; }","duration":"115.587358ms","start":"2026-06-30T09:54:25.582581Z","end":"2026-06-30T09:54:25.698169Z","steps":["trace[1189335849] 'process raft request' (duration: 115.432343ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:26.205793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.868996ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-06-30T09:54:26.20611Z","caller":"traceutil/trace.go:171","msg":"trace[391119722] linearizableReadLoop","detail":"{readStateIndex:26818; appliedIndex:26817; }","duration":"241.595391ms","start":"2026-06-30T09:54:25.964492Z","end":"2026-06-30T09:54:26.206088Z","steps":["trace[391119722] 'read index received' (duration: 98.364825ms)","trace[391119722] 'applied index is now lower than readState.Index' (duration: 143.229466ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:54:26.206245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.743735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:26.206405Z","caller":"traceutil/trace.go:171","msg":"trace[290347729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25967; }","duration":"241.850088ms","start":"2026-06-30T09:54:25.96445Z","end":"2026-06-30T09:54:26.2063Z","steps":["trace[290347729] 'agreement among raft nodes before linearized reading' (duration: 241.713994ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:26.207231Z","caller":"traceutil/trace.go:171","msg":"trace[781676093] transaction","detail":"{read_only:false; response_revision:25968; number_of_response:1; }","duration":"146.647435ms","start":"2026-06-30T09:54:26.060564Z","end":"2026-06-30T09:54:26.207211Z","steps":["trace[781676093] 'process raft request' (duration: 145.382989ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:26.35888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.029845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:26.358955Z","caller":"traceutil/trace.go:171","msg":"trace[1892885064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25968; }","duration":"150.146868ms","start":"2026-06-30T09:54:26.208791Z","end":"2026-06-30T09:54:26.358938Z","steps":["trace[1892885064] 'range keys from in-memory index tree' (duration: 149.949992ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:26.848248Z","caller":"traceutil/trace.go:171","msg":"trace[40787558] transaction","detail":"{read_only:false; response_revision:25971; number_of_response:1; }","duration":"170.830122ms","start":"2026-06-30T09:54:26.677386Z","end":"2026-06-30T09:54:26.848217Z","steps":["trace[40787558] 'process raft request' (duration: 170.599626ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:26.872253Z","caller":"traceutil/trace.go:171","msg":"trace[497016392] transaction","detail":"{read_only:false; response_revision:25972; number_of_response:1; }","duration":"190.024613ms","start":"2026-06-30T09:54:26.682196Z","end":"2026-06-30T09:54:26.872221Z","steps":["trace[497016392] 'process raft request' (duration: 189.830558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:27.154516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.682645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T09:54:27.154882Z","caller":"traceutil/trace.go:171","msg":"trace[233984634] linearizableReadLoop","detail":"{readStateIndex:26825; appliedIndex:26823; }","duration":"189.800968ms","start":"2026-06-30T09:54:26.965059Z","end":"2026-06-30T09:54:27.154859Z","steps":["trace[233984634] 'read index received' (duration: 52.804124ms)","trace[233984634] 'applied index is now lower than readState.Index' (duration: 136.995604ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:27.155005Z","caller":"traceutil/trace.go:171","msg":"trace[1680747824] transaction","detail":"{read_only:false; response_revision:25973; number_of_response:1; }","duration":"237.635806ms","start":"2026-06-30T09:54:26.917355Z","end":"2026-06-30T09:54:27.154991Z","steps":["trace[1680747824] 'process raft request' (duration: 100.355934ms)","trace[1680747824] 'compare' (duration: 136.308313ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:27.155115Z","caller":"traceutil/trace.go:171","msg":"trace[1343763101] transaction","detail":"{read_only:false; response_revision:25975; number_of_response:1; }","duration":"150.265142ms","start":"2026-06-30T09:54:27.004813Z","end":"2026-06-30T09:54:27.155078Z","steps":["trace[1343763101] 'process raft request' (duration: 149.986994ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:27.15516Z","caller":"traceutil/trace.go:171","msg":"trace[2022337678] transaction","detail":"{read_only:false; response_revision:25974; number_of_response:1; }","duration":"191.380943ms","start":"2026-06-30T09:54:26.963769Z","end":"2026-06-30T09:54:27.155149Z","steps":["trace[2022337678] 'process raft request' (duration: 190.899449ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:27.155381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.320963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:27.155423Z","caller":"traceutil/trace.go:171","msg":"trace[396601647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25975; }","duration":"190.414945ms","start":"2026-06-30T09:54:26.964998Z","end":"2026-06-30T09:54:27.155412Z","steps":["trace[396601647] 'agreement among raft nodes before linearized reading' (duration: 190.338883ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:27.373606Z","caller":"traceutil/trace.go:171","msg":"trace[286866029] transaction","detail":"{read_only:false; response_revision:25976; number_of_response:1; }","duration":"208.3398ms","start":"2026-06-30T09:54:27.165235Z","end":"2026-06-30T09:54:27.373575Z","steps":["trace[286866029] 'process raft request' (duration: 170.648458ms)","trace[286866029] 'compare' (duration: 37.521687ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:27.456805Z","caller":"traceutil/trace.go:171","msg":"trace[1319342752] transaction","detail":"{read_only:false; response_revision:25977; number_of_response:1; }","duration":"291.291963ms","start":"2026-06-30T09:54:27.165494Z","end":"2026-06-30T09:54:27.456786Z","steps":["trace[1319342752] 'process raft request' (duration: 290.779697ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:27.456979Z","caller":"traceutil/trace.go:171","msg":"trace[1044360856] transaction","detail":"{read_only:false; response_revision:25979; number_of_response:1; }","duration":"290.710826ms","start":"2026-06-30T09:54:27.166262Z","end":"2026-06-30T09:54:27.456973Z","steps":["trace[1044360856] 'process raft request' (duration: 290.16634ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:27.457026Z","caller":"traceutil/trace.go:171","msg":"trace[264914680] transaction","detail":"{read_only:false; response_revision:25978; number_of_response:1; }","duration":"291.17039ms","start":"2026-06-30T09:54:27.165821Z","end":"2026-06-30T09:54:27.456991Z","steps":["trace[264914680] 'process raft request' (duration: 290.576362ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:27.457028Z","caller":"traceutil/trace.go:171","msg":"trace[1052805297] transaction","detail":"{read_only:false; response_revision:25980; number_of_response:1; }","duration":"225.898302ms","start":"2026-06-30T09:54:27.231027Z","end":"2026-06-30T09:54:27.456925Z","steps":["trace[1052805297] 'process raft request' (duration: 225.428657ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:27.817174Z","caller":"traceutil/trace.go:171","msg":"trace[40396896] transaction","detail":"{read_only:false; response_revision:25982; number_of_response:1; }","duration":"113.428854ms","start":"2026-06-30T09:54:27.703715Z","end":"2026-06-30T09:54:27.817144Z","steps":["trace[40396896] 'process raft request' (duration: 112.845887ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:28.231306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.016985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:28.231381Z","caller":"traceutil/trace.go:171","msg":"trace[1471213727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25983; }","duration":"266.137338ms","start":"2026-06-30T09:54:27.965226Z","end":"2026-06-30T09:54:28.231364Z","steps":["trace[1471213727] 'range keys from in-memory index tree' (duration: 265.915451ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:28.231578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.073679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"warn","ts":"2026-06-30T09:54:28.231607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.829452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:28.231673Z","caller":"traceutil/trace.go:171","msg":"trace[129674660] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25983; }","duration":"222.218904ms","start":"2026-06-30T09:54:28.009428Z","end":"2026-06-30T09:54:28.231647Z","steps":["trace[129674660] 'range keys from in-memory index tree' (duration: 221.708559ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:28.23168Z","caller":"traceutil/trace.go:171","msg":"trace[534546509] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:25983; }","duration":"196.948046ms","start":"2026-06-30T09:54:28.03471Z","end":"2026-06-30T09:54:28.231658Z","steps":["trace[534546509] 'count revisions from in-memory index tree' (duration: 196.687678ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:28.936918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.583416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:28.936991Z","caller":"traceutil/trace.go:171","msg":"trace[1155663966] 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:25985; }","duration":"143.71841ms","start":"2026-06-30T09:54:28.793256Z","end":"2026-06-30T09:54:28.936974Z","steps":["trace[1155663966] 'range keys from in-memory index tree' (duration: 143.483213ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:29.00458Z","caller":"traceutil/trace.go:171","msg":"trace[1582951131] transaction","detail":"{read_only:false; response_revision:25986; number_of_response:1; }","duration":"144.823863ms","start":"2026-06-30T09:54:28.85973Z","end":"2026-06-30T09:54:29.004554Z","steps":["trace[1582951131] 'process raft request' (duration: 144.641018ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:29.444617Z","caller":"traceutil/trace.go:171","msg":"trace[745210680] linearizableReadLoop","detail":"{readStateIndex:26838; appliedIndex:26836; }","duration":"501.168327ms","start":"2026-06-30T09:54:28.94342Z","end":"2026-06-30T09:54:29.444588Z","steps":["trace[745210680] 'read index received' (duration: 61.019483ms)","trace[745210680] 'applied index is now lower than readState.Index' (duration: 440.147764ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:29.444701Z","caller":"traceutil/trace.go:171","msg":"trace[1858720854] transaction","detail":"{read_only:false; response_revision:25987; number_of_response:1; }","duration":"565.889179ms","start":"2026-06-30T09:54:28.878779Z","end":"2026-06-30T09:54:29.444668Z","steps":["trace[1858720854] 'process raft request' (duration: 565.618411ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:29.444812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.365473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-30T09:54:29.444889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:28.878769Z","time spent":"566.008262ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T09:54:29.444901Z","caller":"traceutil/trace.go:171","msg":"trace[345988449] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25987; }","duration":"501.526727ms","start":"2026-06-30T09:54:28.943355Z","end":"2026-06-30T09:54:29.444882Z","steps":["trace[345988449] 'agreement among raft nodes before linearized reading' (duration: 501.348692ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:29.444953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:28.943323Z","time spent":"501.62115ms","remote":"127.0.0.1:58696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-06-30T09:54:29.476271Z","caller":"traceutil/trace.go:171","msg":"trace[1472319063] transaction","detail":"{read_only:false; response_revision:25988; number_of_response:1; }","duration":"314.702357ms","start":"2026-06-30T09:54:29.161541Z","end":"2026-06-30T09:54:29.476243Z","steps":["trace[1472319063] 'process raft request' (duration: 314.341106ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:29.476309Z","caller":"traceutil/trace.go:171","msg":"trace[639527756] transaction","detail":"{read_only:false; response_revision:25989; number_of_response:1; }","duration":"314.412058ms","start":"2026-06-30T09:54:29.161861Z","end":"2026-06-30T09:54:29.476273Z","steps":["trace[639527756] 'process raft request' (duration: 314.220213ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:29.476346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.894343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-30T09:54:29.476458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.18404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:29.4765Z","caller":"traceutil/trace.go:171","msg":"trace[1480910859] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:25989; }","duration":"354.14849ms","start":"2026-06-30T09:54:29.122327Z","end":"2026-06-30T09:54:29.476475Z","steps":["trace[1480910859] 'agreement among raft nodes before linearized reading' (duration: 353.900073ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:29.476518Z","caller":"traceutil/trace.go:171","msg":"trace[1279870106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25989; }","duration":"510.285004ms","start":"2026-06-30T09:54:28.966218Z","end":"2026-06-30T09:54:29.476503Z","steps":["trace[1279870106] 'agreement among raft nodes before linearized reading' (duration: 510.197311ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:29.476486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:29.161503Z","time spent":"314.839181ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T09:54:29.476553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:28.966182Z","time spent":"510.363386ms","remote":"127.0.0.1:58492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T09:54:29.476563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:29.161852Z","time spent":"314.566593ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T09:54:29.476641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:29.122293Z","time spent":"354.254163ms","remote":"127.0.0.1:58812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":32,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-30T09:54:29.47669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.323337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-06-30T09:54:29.476795Z","caller":"traceutil/trace.go:171","msg":"trace[1126739157] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0; range_end:; response_count:1; response_revision:25989; }","duration":"159.450731ms","start":"2026-06-30T09:54:29.317316Z","end":"2026-06-30T09:54:29.476767Z","steps":["trace[1126739157] 'agreement among raft nodes before linearized reading' (duration: 158.905774ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:29.651728Z","caller":"traceutil/trace.go:171","msg":"trace[2106347686] transaction","detail":"{read_only:false; response_revision:25992; number_of_response:1; }","duration":"167.079024ms","start":"2026-06-30T09:54:29.484617Z","end":"2026-06-30T09:54:29.651696Z","steps":["trace[2106347686] 'process raft request' (duration: 166.839867ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:29.65203Z","caller":"traceutil/trace.go:171","msg":"trace[1977730694] transaction","detail":"{read_only:false; response_revision:25993; number_of_response:1; }","duration":"165.894708ms","start":"2026-06-30T09:54:29.486098Z","end":"2026-06-30T09:54:29.651993Z","steps":["trace[1977730694] 'process raft request' (duration: 165.540158ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:29.652171Z","caller":"traceutil/trace.go:171","msg":"trace[1519330974] linearizableReadLoop","detail":"{readStateIndex:26845; appliedIndex:26841; }","duration":"164.853218ms","start":"2026-06-30T09:54:29.487171Z","end":"2026-06-30T09:54:29.652024Z","steps":["trace[1519330974] 'read index received' (duration: 40.232627ms)","trace[1519330974] 'applied index is now lower than readState.Index' (duration: 124.619651ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:29.652202Z","caller":"traceutil/trace.go:171","msg":"trace[529427968] transaction","detail":"{read_only:false; response_revision:25994; number_of_response:1; }","duration":"166.017102ms","start":"2026-06-30T09:54:29.486171Z","end":"2026-06-30T09:54:29.652188Z","steps":["trace[529427968] 'process raft request' (duration: 165.721233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:29.652356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.167867ms","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":"warn","ts":"2026-06-30T09:54:29.652386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.548651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:29.652394Z","caller":"traceutil/trace.go:171","msg":"trace[1199234676] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25994; }","duration":"165.261779ms","start":"2026-06-30T09:54:29.487121Z","end":"2026-06-30T09:54:29.652383Z","steps":["trace[1199234676] 'agreement among raft nodes before linearized reading' (duration: 165.173717ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:29.652425Z","caller":"traceutil/trace.go:171","msg":"trace[1711043091] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:25994; }","duration":"111.651733ms","start":"2026-06-30T09:54:29.540761Z","end":"2026-06-30T09:54:29.652413Z","steps":["trace[1711043091] 'agreement among raft nodes before linearized reading' (duration: 111.556491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:29.94273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.891186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T09:54:29.942866Z","caller":"traceutil/trace.go:171","msg":"trace[1526893872] transaction","detail":"{read_only:false; response_revision:25995; number_of_response:1; }","duration":"285.331149ms","start":"2026-06-30T09:54:29.657508Z","end":"2026-06-30T09:54:29.942839Z","steps":["trace[1526893872] 'process raft request' (duration: 116.219707ms)","trace[1526893872] 'compare' (duration: 168.322789ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:29.963221Z","caller":"traceutil/trace.go:171","msg":"trace[374979623] transaction","detail":"{read_only:false; response_revision:25996; number_of_response:1; }","duration":"136.642044ms","start":"2026-06-30T09:54:29.82654Z","end":"2026-06-30T09:54:29.963182Z","steps":["trace[374979623] 'process raft request' (duration: 136.368226ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:30.369898Z","caller":"traceutil/trace.go:171","msg":"trace[1219473435] transaction","detail":"{read_only:false; response_revision:25997; number_of_response:1; }","duration":"108.440259ms","start":"2026-06-30T09:54:30.261431Z","end":"2026-06-30T09:54:30.369871Z","steps":["trace[1219473435] 'process raft request' (duration: 108.277884ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:30.719048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.998338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:30.719203Z","caller":"traceutil/trace.go:171","msg":"trace[1789263202] 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:25997; }","duration":"248.185154ms","start":"2026-06-30T09:54:30.470992Z","end":"2026-06-30T09:54:30.719177Z","steps":["trace[1789263202] 'count revisions from in-memory index tree' (duration: 247.860144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:30.719601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.892924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.242\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-30T09:54:30.719685Z","caller":"traceutil/trace.go:171","msg":"trace[573539802] range","detail":"{range_begin:/registry/masterleases/199.19.213.242; range_end:; response_count:1; response_revision:25997; }","duration":"197.049279ms","start":"2026-06-30T09:54:30.522615Z","end":"2026-06-30T09:54:30.719664Z","steps":["trace[573539802] 'range keys from in-memory index tree' (duration: 196.583515ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:30.980687Z","caller":"traceutil/trace.go:171","msg":"trace[956373220] transaction","detail":"{read_only:false; response_revision:25998; number_of_response:1; }","duration":"368.058837ms","start":"2026-06-30T09:54:30.612603Z","end":"2026-06-30T09:54:30.980662Z","steps":["trace[956373220] 'process raft request' (duration: 367.897432ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:30.980865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:30.612573Z","time spent":"368.18956ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T09:54:30.995044Z","caller":"traceutil/trace.go:171","msg":"trace[266712429] linearizableReadLoop","detail":"{readStateIndex:26850; appliedIndex:26849; }","duration":"221.055341ms","start":"2026-06-30T09:54:30.773946Z","end":"2026-06-30T09:54:30.995002Z","steps":["trace[266712429] 'read index received' (duration: 206.814543ms)","trace[266712429] 'applied index is now lower than readState.Index' (duration: 14.238508ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:54:30.995357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.37835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T09:54:30.995414Z","caller":"traceutil/trace.go:171","msg":"trace[822121364] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:25998; }","duration":"221.510534ms","start":"2026-06-30T09:54:30.773885Z","end":"2026-06-30T09:54:30.995396Z","steps":["trace[822121364] 'agreement among raft nodes before linearized reading' (duration: 221.352409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:30.995435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.443201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:30.995526Z","caller":"traceutil/trace.go:171","msg":"trace[897896093] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:25998; }","duration":"171.588666ms","start":"2026-06-30T09:54:30.82391Z","end":"2026-06-30T09:54:30.995499Z","steps":["trace[897896093] 'agreement among raft nodes before linearized reading' (duration: 171.449422ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:30.995849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.845243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:30.99588Z","caller":"traceutil/trace.go:171","msg":"trace[181889090] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:25998; }","duration":"106.903635ms","start":"2026-06-30T09:54:30.888969Z","end":"2026-06-30T09:54:30.995873Z","steps":["trace[181889090] 'agreement among raft nodes before linearized reading' (duration: 106.851463ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:39.516756Z","caller":"traceutil/trace.go:171","msg":"trace[2124244665] linearizableReadLoop","detail":"{readStateIndex:26975; appliedIndex:26974; }","duration":"230.441046ms","start":"2026-06-30T09:54:39.286286Z","end":"2026-06-30T09:54:39.516727Z","steps":["trace[2124244665] 'read index received' (duration: 230.308941ms)","trace[2124244665] 'applied index is now lower than readState.Index' (duration: 130.424µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:39.516806Z","caller":"traceutil/trace.go:171","msg":"trace[1148886418] transaction","detail":"{read_only:false; response_revision:26121; number_of_response:1; }","duration":"267.832348ms","start":"2026-06-30T09:54:39.248934Z","end":"2026-06-30T09:54:39.516767Z","steps":["trace[1148886418] 'process raft request' (duration: 267.60798ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:39.517002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.687512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:39.517098Z","caller":"traceutil/trace.go:171","msg":"trace[2109927204] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:26121; }","duration":"230.843837ms","start":"2026-06-30T09:54:39.286233Z","end":"2026-06-30T09:54:39.517077Z","steps":["trace[2109927204] 'agreement among raft nodes before linearized reading' (duration: 230.649391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:39.76554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.019455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T09:54:39.76582Z","caller":"traceutil/trace.go:171","msg":"trace[616550816] linearizableReadLoop","detail":"{readStateIndex:26976; appliedIndex:26975; }","duration":"248.958555ms","start":"2026-06-30T09:54:39.516847Z","end":"2026-06-30T09:54:39.765805Z","steps":["trace[616550816] 'read index received' (duration: 99.58463ms)","trace[616550816] 'applied index is now lower than readState.Index' (duration: 149.373035ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:39.765847Z","caller":"traceutil/trace.go:171","msg":"trace[1421696789] transaction","detail":"{read_only:false; response_revision:26122; number_of_response:1; }","duration":"283.662819ms","start":"2026-06-30T09:54:39.482152Z","end":"2026-06-30T09:54:39.765815Z","steps":["trace[1421696789] 'process raft request' (duration: 134.260613ms)","trace[1421696789] 'compare' (duration: 148.900532ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:54:39.765904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.057225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:39.766031Z","caller":"traceutil/trace.go:171","msg":"trace[440149114] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:26122; }","duration":"408.222891ms","start":"2026-06-30T09:54:39.357798Z","end":"2026-06-30T09:54:39.766021Z","steps":["trace[440149114] 'agreement among raft nodes before linearized reading' (duration: 408.068836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:39.766071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:39.357776Z","time spent":"408.281502ms","remote":"127.0.0.1:48058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"info","ts":"2026-06-30T09:54:39.855064Z","caller":"traceutil/trace.go:171","msg":"trace[388528727] transaction","detail":"{read_only:false; response_revision:26123; number_of_response:1; }","duration":"331.618971ms","start":"2026-06-30T09:54:39.523414Z","end":"2026-06-30T09:54:39.855033Z","steps":["trace[388528727] 'process raft request' (duration: 331.404795ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:39.855238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:39.523384Z","time spent":"331.764745ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T09:54:39.855474Z","caller":"traceutil/trace.go:171","msg":"trace[1877854692] transaction","detail":"{read_only:false; response_revision:26125; number_of_response:1; }","duration":"331.164098ms","start":"2026-06-30T09:54:39.52428Z","end":"2026-06-30T09:54:39.855444Z","steps":["trace[1877854692] 'process raft request' (duration: 330.927971ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:39.855495Z","caller":"traceutil/trace.go:171","msg":"trace[1424822954] transaction","detail":"{read_only:false; response_revision:26124; number_of_response:1; }","duration":"331.351014ms","start":"2026-06-30T09:54:39.524117Z","end":"2026-06-30T09:54:39.855468Z","steps":["trace[1424822954] 'process raft request' (duration: 330.86152ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:39.855532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.672594ms","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":"warn","ts":"2026-06-30T09:54:39.855574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:39.524253Z","time spent":"331.274401ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T09:54:39.855582Z","caller":"traceutil/trace.go:171","msg":"trace[559618803] 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:26125; }","duration":"188.780946ms","start":"2026-06-30T09:54:39.666786Z","end":"2026-06-30T09:54:39.855567Z","steps":["trace[559618803] 'agreement among raft nodes before linearized reading' (duration: 188.686014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:39.855509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.321264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-30T09:54:39.855653Z","caller":"traceutil/trace.go:171","msg":"trace[739283444] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26125; }","duration":"162.52435ms","start":"2026-06-30T09:54:39.693116Z","end":"2026-06-30T09:54:39.85564Z","steps":["trace[739283444] 'agreement among raft nodes before linearized reading' (duration: 162.247482ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:39.855641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:39.524103Z","time spent":"331.464847ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T09:54:39.85564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.436962ms","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-06-30T09:54:39.855684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.347024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-30T09:54:39.855757Z","caller":"traceutil/trace.go:171","msg":"trace[1723514284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26125; }","duration":"150.465107ms","start":"2026-06-30T09:54:39.705274Z","end":"2026-06-30T09:54:39.855739Z","steps":["trace[1723514284] 'agreement among raft nodes before linearized reading' (duration: 150.087386ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:39.855712Z","caller":"traceutil/trace.go:171","msg":"trace[1119199723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26125; }","duration":"232.566988ms","start":"2026-06-30T09:54:39.623128Z","end":"2026-06-30T09:54:39.855695Z","steps":["trace[1119199723] 'agreement among raft nodes before linearized reading' (duration: 232.257328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:39.856007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.731705ms","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-06-30T09:54:39.856044Z","caller":"traceutil/trace.go:171","msg":"trace[1794308755] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26125; }","duration":"138.769406ms","start":"2026-06-30T09:54:39.717266Z","end":"2026-06-30T09:54:39.856036Z","steps":["trace[1794308755] 'agreement among raft nodes before linearized reading' (duration: 138.171328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:40.054413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.480259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T09:54:40.054512Z","caller":"traceutil/trace.go:171","msg":"trace[828771812] linearizableReadLoop","detail":"{readStateIndex:26980; appliedIndex:26979; }","duration":"193.098952ms","start":"2026-06-30T09:54:39.861397Z","end":"2026-06-30T09:54:40.054496Z","steps":["trace[828771812] 'read index received' (duration: 56.991295ms)","trace[828771812] 'applied index is now lower than readState.Index' (duration: 136.106147ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:40.054558Z","caller":"traceutil/trace.go:171","msg":"trace[1855185536] transaction","detail":"{read_only:false; response_revision:26126; number_of_response:1; }","duration":"193.645909ms","start":"2026-06-30T09:54:39.860884Z","end":"2026-06-30T09:54:40.05453Z","steps":["trace[1855185536] 'process raft request' (duration: 57.46368ms)","trace[1855185536] 'compare' (duration: 135.308054ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:54:40.054797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.333981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:40.054996Z","caller":"traceutil/trace.go:171","msg":"trace[1115589679] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26126; }","duration":"193.527696ms","start":"2026-06-30T09:54:39.861368Z","end":"2026-06-30T09:54:40.054896Z","steps":["trace[1115589679] 'agreement among raft nodes before linearized reading' (duration: 193.246748ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:40.130166Z","caller":"traceutil/trace.go:171","msg":"trace[894653432] transaction","detail":"{read_only:false; response_revision:26127; number_of_response:1; }","duration":"268.531808ms","start":"2026-06-30T09:54:39.861594Z","end":"2026-06-30T09:54:40.130125Z","steps":["trace[894653432] 'process raft request' (duration: 268.28399ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:40.130549Z","caller":"traceutil/trace.go:171","msg":"trace[1675239719] transaction","detail":"{read_only:false; response_revision:26128; number_of_response:1; }","duration":"268.876339ms","start":"2026-06-30T09:54:39.86163Z","end":"2026-06-30T09:54:40.130507Z","steps":["trace[1675239719] 'process raft request' (duration: 268.445175ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:40.130622Z","caller":"traceutil/trace.go:171","msg":"trace[152077852] transaction","detail":"{read_only:false; response_revision:26129; number_of_response:1; }","duration":"268.312132ms","start":"2026-06-30T09:54:39.862271Z","end":"2026-06-30T09:54:40.130583Z","steps":["trace[152077852] 'process raft request' (duration: 268.050944ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:40.265823Z","caller":"traceutil/trace.go:171","msg":"trace[1520201310] linearizableReadLoop","detail":"{readStateIndex:26984; appliedIndex:26980; }","duration":"211.211203ms","start":"2026-06-30T09:54:40.054539Z","end":"2026-06-30T09:54:40.26575Z","steps":["trace[1520201310] 'read index received' (duration: 75.390653ms)","trace[1520201310] 'applied index is now lower than readState.Index' (duration: 135.81755ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:40.266056Z","caller":"traceutil/trace.go:171","msg":"trace[89118548] transaction","detail":"{read_only:false; response_revision:26130; number_of_response:1; }","duration":"255.166228ms","start":"2026-06-30T09:54:40.010814Z","end":"2026-06-30T09:54:40.26598Z","steps":["trace[89118548] 'process raft request' (duration: 186.550742ms)","trace[89118548] 'compare' (duration: 68.026139ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:54:40.266293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.248874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:40.266337Z","caller":"traceutil/trace.go:171","msg":"trace[1005828676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26130; }","duration":"301.338407ms","start":"2026-06-30T09:54:39.964987Z","end":"2026-06-30T09:54:40.266326Z","steps":["trace[1005828676] 'agreement among raft nodes before linearized reading' (duration: 301.240544ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:40.266383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:39.964969Z","time spent":"301.390128ms","remote":"127.0.0.1:58492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T09:54:40.266336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.64719ms","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":"warn","ts":"2026-06-30T09:54:40.266411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.126247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-30T09:54:40.266468Z","caller":"traceutil/trace.go:171","msg":"trace[1644834923] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26130; }","duration":"205.932868ms","start":"2026-06-30T09:54:40.060509Z","end":"2026-06-30T09:54:40.266442Z","steps":["trace[1644834923] 'agreement among raft nodes before linearized reading' (duration: 205.698561ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:40.266476Z","caller":"traceutil/trace.go:171","msg":"trace[330065219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26130; }","duration":"255.229779ms","start":"2026-06-30T09:54:40.011229Z","end":"2026-06-30T09:54:40.266459Z","steps":["trace[330065219] 'agreement among raft nodes before linearized reading' (duration: 254.823268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:40.26657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.06346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-06-30T09:54:40.266701Z","caller":"traceutil/trace.go:171","msg":"trace[1215960094] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:26130; }","duration":"403.259036ms","start":"2026-06-30T09:54:39.863412Z","end":"2026-06-30T09:54:40.266671Z","steps":["trace[1215960094] 'agreement among raft nodes before linearized reading' (duration: 402.70869ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:40.26678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:39.863403Z","time spent":"403.352908ms","remote":"127.0.0.1:58598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1336,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"info","ts":"2026-06-30T09:54:40.474276Z","caller":"traceutil/trace.go:171","msg":"trace[1389366561] linearizableReadLoop","detail":"{readStateIndex:26987; appliedIndex:26986; }","duration":"113.771734ms","start":"2026-06-30T09:54:40.360468Z","end":"2026-06-30T09:54:40.47424Z","steps":["trace[1389366561] 'read index received' (duration: 71.117558ms)","trace[1389366561] 'applied index is now lower than readState.Index' (duration: 42.652746ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:40.474472Z","caller":"traceutil/trace.go:171","msg":"trace[1785447655] transaction","detail":"{read_only:false; response_revision:26133; number_of_response:1; }","duration":"116.515395ms","start":"2026-06-30T09:54:40.357922Z","end":"2026-06-30T09:54:40.474437Z","steps":["trace[1785447655] 'process raft request' (duration: 73.693594ms)","trace[1785447655] 'compare' (duration: 42.45497ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:54:40.474644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.143815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-06-30T09:54:40.474698Z","caller":"traceutil/trace.go:171","msg":"trace[1165432596] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26133; }","duration":"114.282709ms","start":"2026-06-30T09:54:40.3604Z","end":"2026-06-30T09:54:40.474682Z","steps":["trace[1165432596] 'agreement among raft nodes before linearized reading' (duration: 114.023141ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:42.684617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.991639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-30T09:54:42.685053Z","caller":"traceutil/trace.go:171","msg":"trace[1217539876] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:26151; }","duration":"245.468543ms","start":"2026-06-30T09:54:42.439562Z","end":"2026-06-30T09:54:42.68503Z","steps":["trace[1217539876] 'count revisions from in-memory index tree' (duration: 244.917218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:42.68564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.940016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T09:54:42.685907Z","caller":"traceutil/trace.go:171","msg":"trace[839389578] transaction","detail":"{read_only:false; response_revision:26152; number_of_response:1; }","duration":"203.22304ms","start":"2026-06-30T09:54:42.482642Z","end":"2026-06-30T09:54:42.685865Z","steps":["trace[839389578] 'compare' (duration: 196.40841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:48.118279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.606733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T09:54:48.118553Z","caller":"traceutil/trace.go:171","msg":"trace[737957337] transaction","detail":"{read_only:false; response_revision:26206; number_of_response:1; }","duration":"234.748621ms","start":"2026-06-30T09:54:47.883704Z","end":"2026-06-30T09:54:48.118453Z","steps":["trace[737957337] 'process raft request' (duration: 124.86302ms)","trace[737957337] 'compare' (duration: 109.463729ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:48.21268Z","caller":"traceutil/trace.go:171","msg":"trace[120488502] linearizableReadLoop","detail":"{readStateIndex:27064; appliedIndex:27062; }","duration":"119.169194ms","start":"2026-06-30T09:54:48.093482Z","end":"2026-06-30T09:54:48.212652Z","steps":["trace[120488502] 'read index received' (duration: 118.872515ms)","trace[120488502] 'applied index is now lower than readState.Index' (duration: 295.559µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:48.212848Z","caller":"traceutil/trace.go:171","msg":"trace[2085915974] transaction","detail":"{read_only:false; response_revision:26208; number_of_response:1; }","duration":"325.453661ms","start":"2026-06-30T09:54:47.887382Z","end":"2026-06-30T09:54:48.212836Z","steps":["trace[2085915974] 'process raft request' (duration: 325.208444ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:48.212899Z","caller":"traceutil/trace.go:171","msg":"trace[1877104137] transaction","detail":"{read_only:false; response_revision:26207; number_of_response:1; }","duration":"327.0967ms","start":"2026-06-30T09:54:47.885766Z","end":"2026-06-30T09:54:48.212863Z","steps":["trace[1877104137] 'process raft request' (duration: 326.616006ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:48.21299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:47.887374Z","time spent":"325.505853ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T09:54:48.213189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:54:47.885755Z","time spent":"327.234243ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T09:54:48.213498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.984377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-30T09:54:48.213613Z","caller":"traceutil/trace.go:171","msg":"trace[224590404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26208; }","duration":"120.187163ms","start":"2026-06-30T09:54:48.0934Z","end":"2026-06-30T09:54:48.213587Z","steps":["trace[224590404] 'agreement among raft nodes before linearized reading' (duration: 119.970966ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:50.622757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.768563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:50.62285Z","caller":"traceutil/trace.go:171","msg":"trace[66863494] 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:26233; }","duration":"258.887226ms","start":"2026-06-30T09:54:50.363943Z","end":"2026-06-30T09:54:50.62283Z","steps":["trace[66863494] 'range keys from in-memory index tree' (duration: 258.69756ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:50.623317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.993317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-30T09:54:50.623359Z","caller":"traceutil/trace.go:171","msg":"trace[861310884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26233; }","duration":"236.09042ms","start":"2026-06-30T09:54:50.387259Z","end":"2026-06-30T09:54:50.623349Z","steps":["trace[861310884] 'range keys from in-memory index tree' (duration: 235.801931ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:50.769818Z","caller":"traceutil/trace.go:171","msg":"trace[343364439] transaction","detail":"{read_only:false; response_revision:26234; number_of_response:1; }","duration":"139.103215ms","start":"2026-06-30T09:54:50.630684Z","end":"2026-06-30T09:54:50.769787Z","steps":["trace[343364439] 'process raft request' (duration: 138.843647ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:50.770005Z","caller":"traceutil/trace.go:171","msg":"trace[1276403680] linearizableReadLoop","detail":"{readStateIndex:27091; appliedIndex:27089; }","duration":"135.098009ms","start":"2026-06-30T09:54:50.63487Z","end":"2026-06-30T09:54:50.769968Z","steps":["trace[1276403680] 'read index received' (duration: 127.646911ms)","trace[1276403680] 'applied index is now lower than readState.Index' (duration: 7.449218ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:54:50.770277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.351087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:50.770428Z","caller":"traceutil/trace.go:171","msg":"trace[316337729] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26235; }","duration":"135.591413ms","start":"2026-06-30T09:54:50.634806Z","end":"2026-06-30T09:54:50.770398Z","steps":["trace[316337729] 'agreement among raft nodes before linearized reading' (duration: 135.307665ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:50.770546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.661898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:54:50.770601Z","caller":"traceutil/trace.go:171","msg":"trace[1302579066] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:26236; }","duration":"119.777291ms","start":"2026-06-30T09:54:50.650813Z","end":"2026-06-30T09:54:50.77059Z","steps":["trace[1302579066] 'agreement among raft nodes before linearized reading' (duration: 119.604306ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:54:52.949766Z","caller":"traceutil/trace.go:171","msg":"trace[1474837324] linearizableReadLoop","detail":"{readStateIndex:27113; appliedIndex:27111; }","duration":"169.354651ms","start":"2026-06-30T09:54:52.780391Z","end":"2026-06-30T09:54:52.949746Z","steps":["trace[1474837324] 'read index received' (duration: 32.002026ms)","trace[1474837324] 'applied index is now lower than readState.Index' (duration: 137.351975ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:54:52.949887Z","caller":"traceutil/trace.go:171","msg":"trace[161894134] transaction","detail":"{read_only:false; response_revision:26256; number_of_response:1; }","duration":"172.114261ms","start":"2026-06-30T09:54:52.777762Z","end":"2026-06-30T09:54:52.949877Z","steps":["trace[161894134] 'process raft request' (duration: 97.049587ms)","trace[161894134] 'compare' (duration: 74.819757ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:54:52.950108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.69156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T09:54:52.950217Z","caller":"traceutil/trace.go:171","msg":"trace[287873703] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:26256; }","duration":"169.862224ms","start":"2026-06-30T09:54:52.780325Z","end":"2026-06-30T09:54:52.950187Z","steps":["trace[287873703] 'agreement among raft nodes before linearized reading' (duration: 169.665599ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:54:52.950191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.836535ms","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-06-30T09:54:52.950343Z","caller":"traceutil/trace.go:171","msg":"trace[1056152925] 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:26256; }","duration":"117.142614ms","start":"2026-06-30T09:54:52.833161Z","end":"2026-06-30T09:54:52.950304Z","steps":["trace[1056152925] 'agreement among raft nodes before linearized reading' (duration: 116.801444ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:03.410343Z","caller":"traceutil/trace.go:171","msg":"trace[43761279] transaction","detail":"{read_only:false; response_revision:26348; number_of_response:1; }","duration":"149.51308ms","start":"2026-06-30T09:55:03.260785Z","end":"2026-06-30T09:55:03.410298Z","steps":["trace[43761279] 'process raft request' (duration: 149.341015ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:55:04.072071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.560377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-06-30T09:55:04.072127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.346779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:55:04.072213Z","caller":"traceutil/trace.go:171","msg":"trace[539889360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26349; }","duration":"104.467763ms","start":"2026-06-30T09:55:03.967725Z","end":"2026-06-30T09:55:04.072193Z","steps":["trace[539889360] 'range keys from in-memory index tree' (duration: 104.251987ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:04.072205Z","caller":"traceutil/trace.go:171","msg":"trace[1385530568] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26349; }","duration":"103.730551ms","start":"2026-06-30T09:55:03.968446Z","end":"2026-06-30T09:55:04.072177Z","steps":["trace[1385530568] 'range keys from in-memory index tree' (duration: 103.389562ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:04.460681Z","caller":"traceutil/trace.go:171","msg":"trace[1728708494] transaction","detail":"{read_only:false; response_revision:26353; number_of_response:1; }","duration":"187.86551ms","start":"2026-06-30T09:55:04.272785Z","end":"2026-06-30T09:55:04.460651Z","steps":["trace[1728708494] 'process raft request' (duration: 180.045281ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:04.462987Z","caller":"traceutil/trace.go:171","msg":"trace[1334566486] transaction","detail":"{read_only:false; response_revision:26354; number_of_response:1; }","duration":"189.451576ms","start":"2026-06-30T09:55:04.273509Z","end":"2026-06-30T09:55:04.46296Z","steps":["trace[1334566486] 'process raft request' (duration: 189.24081ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:04.463086Z","caller":"traceutil/trace.go:171","msg":"trace[1278738755] linearizableReadLoop","detail":"{readStateIndex:27213; appliedIndex:27211; }","duration":"153.106954ms","start":"2026-06-30T09:55:04.309949Z","end":"2026-06-30T09:55:04.463056Z","steps":["trace[1278738755] 'read index received' (duration: 142.916207ms)","trace[1278738755] 'applied index is now lower than readState.Index' (duration: 10.185267ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:55:04.463157Z","caller":"traceutil/trace.go:171","msg":"trace[626279564] transaction","detail":"{read_only:false; response_revision:26355; number_of_response:1; }","duration":"108.213123ms","start":"2026-06-30T09:55:04.354903Z","end":"2026-06-30T09:55:04.463116Z","steps":["trace[626279564] 'process raft request' (duration: 107.997206ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:55:04.463368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.51585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-30T09:55:04.463413Z","caller":"traceutil/trace.go:171","msg":"trace[928559994] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26355; }","duration":"150.593521ms","start":"2026-06-30T09:55:04.312808Z","end":"2026-06-30T09:55:04.463402Z","steps":["trace[928559994] 'agreement among raft nodes before linearized reading' (duration: 150.400976ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:55:04.463402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.147482ms","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-06-30T09:55:04.463415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.440245ms","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-06-30T09:55:04.463414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.237803ms","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-06-30T09:55:04.463502Z","caller":"traceutil/trace.go:171","msg":"trace[1741875925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26355; }","duration":"145.261066ms","start":"2026-06-30T09:55:04.318215Z","end":"2026-06-30T09:55:04.463476Z","steps":["trace[1741875925] 'agreement among raft nodes before linearized reading' (duration: 144.998838ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:04.463579Z","caller":"traceutil/trace.go:171","msg":"trace[150119825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26355; }","duration":"153.603179ms","start":"2026-06-30T09:55:04.309898Z","end":"2026-06-30T09:55:04.463501Z","steps":["trace[150119825] 'agreement among raft nodes before linearized reading' (duration: 153.248899ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:04.463622Z","caller":"traceutil/trace.go:171","msg":"trace[1065085693] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26355; }","duration":"148.48239ms","start":"2026-06-30T09:55:04.315111Z","end":"2026-06-30T09:55:04.463594Z","steps":["trace[1065085693] 'agreement among raft nodes before linearized reading' (duration: 148.168181ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:04.621849Z","caller":"traceutil/trace.go:171","msg":"trace[2083703888] transaction","detail":"{read_only:false; response_revision:26358; number_of_response:1; }","duration":"153.564268ms","start":"2026-06-30T09:55:04.468237Z","end":"2026-06-30T09:55:04.621801Z","steps":["trace[2083703888] 'process raft request' (duration: 153.409054ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:04.621899Z","caller":"traceutil/trace.go:171","msg":"trace[1540460655] transaction","detail":"{read_only:false; response_revision:26359; number_of_response:1; }","duration":"153.026412ms","start":"2026-06-30T09:55:04.468845Z","end":"2026-06-30T09:55:04.621871Z","steps":["trace[1540460655] 'process raft request' (duration: 152.921899ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:04.621892Z","caller":"traceutil/trace.go:171","msg":"trace[1629969569] transaction","detail":"{read_only:false; response_revision:26357; number_of_response:1; }","duration":"153.944789ms","start":"2026-06-30T09:55:04.467892Z","end":"2026-06-30T09:55:04.621837Z","steps":["trace[1629969569] 'process raft request' (duration: 153.194838ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:05.267298Z","caller":"traceutil/trace.go:171","msg":"trace[995585440] transaction","detail":"{read_only:false; response_revision:26362; number_of_response:1; }","duration":"119.914665ms","start":"2026-06-30T09:55:05.147344Z","end":"2026-06-30T09:55:05.267258Z","steps":["trace[995585440] 'process raft request' (duration: 119.614815ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:10.620497Z","caller":"traceutil/trace.go:171","msg":"trace[907722488] transaction","detail":"{read_only:false; response_revision:26410; number_of_response:1; }","duration":"136.275652ms","start":"2026-06-30T09:55:10.484136Z","end":"2026-06-30T09:55:10.620412Z","steps":["trace[907722488] 'process raft request' (duration: 136.095927ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:10.728438Z","caller":"traceutil/trace.go:171","msg":"trace[1636159973] linearizableReadLoop","detail":"{readStateIndex:27272; appliedIndex:27270; }","duration":"202.997852ms","start":"2026-06-30T09:55:10.525393Z","end":"2026-06-30T09:55:10.728391Z","steps":["trace[1636159973] 'read index received' (duration: 95.390158ms)","trace[1636159973] 'applied index is now lower than readState.Index' (duration: 107.606304ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:55:10.728974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.75406ms","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":"warn","ts":"2026-06-30T09:55:10.728967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.542878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.242\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-30T09:55:10.728984Z","caller":"traceutil/trace.go:171","msg":"trace[675172562] transaction","detail":"{read_only:false; response_revision:26411; number_of_response:1; }","duration":"243.547428ms","start":"2026-06-30T09:55:10.485387Z","end":"2026-06-30T09:55:10.728934Z","steps":["trace[675172562] 'process raft request' (duration: 174.029866ms)","trace[675172562] 'compare' (duration: 68.716889ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:55:10.729061Z","caller":"traceutil/trace.go:171","msg":"trace[258841796] range","detail":"{range_begin:/registry/masterleases/199.19.213.242; range_end:; response_count:1; response_revision:26412; }","duration":"203.696663ms","start":"2026-06-30T09:55:10.525343Z","end":"2026-06-30T09:55:10.72904Z","steps":["trace[258841796] 'agreement among raft nodes before linearized reading' (duration: 203.402364ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:55:10.729129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.060627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T09:55:10.72917Z","caller":"traceutil/trace.go:171","msg":"trace[1159263192] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:26412; }","duration":"186.15383ms","start":"2026-06-30T09:55:10.543008Z","end":"2026-06-30T09:55:10.729161Z","steps":["trace[1159263192] 'agreement among raft nodes before linearized reading' (duration: 186.054458ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:10.729035Z","caller":"traceutil/trace.go:171","msg":"trace[1269497873] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26412; }","duration":"196.878223ms","start":"2026-06-30T09:55:10.532139Z","end":"2026-06-30T09:55:10.729017Z","steps":["trace[1269497873] 'agreement among raft nodes before linearized reading' (duration: 196.661087ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:55:10.729222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.08294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-06-30T09:55:10.729317Z","caller":"traceutil/trace.go:171","msg":"trace[1102418111] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26412; }","duration":"183.215384ms","start":"2026-06-30T09:55:10.546078Z","end":"2026-06-30T09:55:10.729294Z","steps":["trace[1102418111] 'agreement among raft nodes before linearized reading' (duration: 182.915696ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:10.729876Z","caller":"traceutil/trace.go:171","msg":"trace[1689584495] transaction","detail":"{read_only:false; response_revision:26412; number_of_response:1; }","duration":"241.395466ms","start":"2026-06-30T09:55:10.488468Z","end":"2026-06-30T09:55:10.729863Z","steps":["trace[1689584495] 'process raft request' (duration: 239.85583ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:10.906252Z","caller":"traceutil/trace.go:171","msg":"trace[10206695] linearizableReadLoop","detail":"{readStateIndex:27277; appliedIndex:27272; }","duration":"169.811692ms","start":"2026-06-30T09:55:10.736413Z","end":"2026-06-30T09:55:10.906225Z","steps":["trace[10206695] 'read index received' (duration: 79.637867ms)","trace[10206695] 'applied index is now lower than readState.Index' (duration: 90.172345ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:55:10.906543Z","caller":"traceutil/trace.go:171","msg":"trace[1764808048] transaction","detail":"{read_only:false; response_revision:26413; number_of_response:1; }","duration":"170.555395ms","start":"2026-06-30T09:55:10.735971Z","end":"2026-06-30T09:55:10.906527Z","steps":["trace[1764808048] 'process raft request' (duration: 158.636636ms)","trace[1764808048] 'compare' (duration: 11.049443ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:55:10.906766Z","caller":"traceutil/trace.go:171","msg":"trace[104158208] transaction","detail":"{read_only:false; response_revision:26414; number_of_response:1; }","duration":"170.635576ms","start":"2026-06-30T09:55:10.736085Z","end":"2026-06-30T09:55:10.906721Z","steps":["trace[104158208] 'process raft request' (duration: 170.005787ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:10.906866Z","caller":"traceutil/trace.go:171","msg":"trace[1068148252] transaction","detail":"{read_only:false; response_revision:26415; number_of_response:1; }","duration":"170.719779ms","start":"2026-06-30T09:55:10.736139Z","end":"2026-06-30T09:55:10.906859Z","steps":["trace[1068148252] 'process raft request' (duration: 170.001968ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:10.906962Z","caller":"traceutil/trace.go:171","msg":"trace[770164886] transaction","detail":"{read_only:false; response_revision:26416; number_of_response:1; }","duration":"170.653527ms","start":"2026-06-30T09:55:10.736302Z","end":"2026-06-30T09:55:10.906956Z","steps":["trace[770164886] 'process raft request' (duration: 169.876844ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:55:10.907176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.73463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-06-30T09:55:10.907208Z","caller":"traceutil/trace.go:171","msg":"trace[376291818] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:26416; }","duration":"170.792101ms","start":"2026-06-30T09:55:10.736408Z","end":"2026-06-30T09:55:10.9072Z","steps":["trace[376291818] 'agreement among raft nodes before linearized reading' (duration: 170.628537ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:55:10.932122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.626085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:55:10.932201Z","caller":"traceutil/trace.go:171","msg":"trace[2060128881] 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:26418; }","duration":"108.730177ms","start":"2026-06-30T09:55:10.823453Z","end":"2026-06-30T09:55:10.932183Z","steps":["trace[2060128881] 'agreement among raft nodes before linearized reading' (duration: 108.542192ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:10.932405Z","caller":"traceutil/trace.go:171","msg":"trace[1252777296] transaction","detail":"{read_only:false; response_revision:26417; number_of_response:1; }","duration":"114.549598ms","start":"2026-06-30T09:55:10.817827Z","end":"2026-06-30T09:55:10.932376Z","steps":["trace[1252777296] 'process raft request' (duration: 113.946471ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:13.494707Z","caller":"traceutil/trace.go:171","msg":"trace[1620524175] transaction","detail":"{read_only:false; response_revision:26438; number_of_response:1; }","duration":"199.414828ms","start":"2026-06-30T09:55:13.295271Z","end":"2026-06-30T09:55:13.494686Z","steps":["trace[1620524175] 'process raft request' (duration: 180.138876ms)","trace[1620524175] 'compare' (duration: 19.139388ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:55:23.665375Z","caller":"traceutil/trace.go:171","msg":"trace[214590703] transaction","detail":"{read_only:false; response_revision:26520; number_of_response:1; }","duration":"137.954431ms","start":"2026-06-30T09:55:23.527381Z","end":"2026-06-30T09:55:23.665335Z","steps":["trace[214590703] 'process raft request' (duration: 137.705214ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:23.672697Z","caller":"traceutil/trace.go:171","msg":"trace[435389406] transaction","detail":"{read_only:false; response_revision:26521; number_of_response:1; }","duration":"131.024289ms","start":"2026-06-30T09:55:23.541633Z","end":"2026-06-30T09:55:23.672657Z","steps":["trace[435389406] 'process raft request' (duration: 130.761621ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:55:23.894161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.176952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T09:55:23.894239Z","caller":"traceutil/trace.go:171","msg":"trace[1246717868] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26521; }","duration":"133.323347ms","start":"2026-06-30T09:55:23.760898Z","end":"2026-06-30T09:55:23.894222Z","steps":["trace[1246717868] 'count revisions from in-memory index tree' (duration: 133.068069ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:24.252407Z","caller":"traceutil/trace.go:171","msg":"trace[1122859743] transaction","detail":"{read_only:false; response_revision:26522; number_of_response:1; }","duration":"193.61914ms","start":"2026-06-30T09:55:24.058751Z","end":"2026-06-30T09:55:24.25237Z","steps":["trace[1122859743] 'process raft request' (duration: 193.352222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:55:24.528767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.793339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T09:55:24.528921Z","caller":"traceutil/trace.go:171","msg":"trace[1535855569] linearizableReadLoop","detail":"{readStateIndex:27386; appliedIndex:27385; }","duration":"173.601954ms","start":"2026-06-30T09:55:24.355291Z","end":"2026-06-30T09:55:24.528893Z","steps":["trace[1535855569] 'read index received' (duration: 50.508786ms)","trace[1535855569] 'applied index is now lower than readState.Index' (duration: 123.091748ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:55:24.529208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.889832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T09:55:24.529314Z","caller":"traceutil/trace.go:171","msg":"trace[1357489845] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26523; }","duration":"174.013155ms","start":"2026-06-30T09:55:24.355283Z","end":"2026-06-30T09:55:24.529296Z","steps":["trace[1357489845] 'agreement among raft nodes before linearized reading' (duration: 173.83135ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:24.529111Z","caller":"traceutil/trace.go:171","msg":"trace[1785763039] transaction","detail":"{read_only:false; response_revision:26523; number_of_response:1; }","duration":"176.297163ms","start":"2026-06-30T09:55:24.352799Z","end":"2026-06-30T09:55:24.529097Z","steps":["trace[1785763039] 'process raft request' (duration: 53.011219ms)","trace[1785763039] 'compare' (duration: 122.567673ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:55:24.5292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.819723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:55:24.529647Z","caller":"traceutil/trace.go:171","msg":"trace[347432875] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:26523; }","duration":"105.329269ms","start":"2026-06-30T09:55:24.424307Z","end":"2026-06-30T09:55:24.529636Z","steps":["trace[347432875] 'agreement among raft nodes before linearized reading' (duration: 104.795033ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:34.418704Z","caller":"traceutil/trace.go:171","msg":"trace[1986890067] transaction","detail":"{read_only:false; response_revision:26605; number_of_response:1; }","duration":"129.886837ms","start":"2026-06-30T09:55:34.288781Z","end":"2026-06-30T09:55:34.418668Z","steps":["trace[1986890067] 'process raft request' (duration: 129.67439ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:34.774781Z","caller":"traceutil/trace.go:171","msg":"trace[1156924174] transaction","detail":"{read_only:false; response_revision:26606; number_of_response:1; }","duration":"210.461341ms","start":"2026-06-30T09:55:34.564293Z","end":"2026-06-30T09:55:34.774754Z","steps":["trace[1156924174] 'process raft request' (duration: 210.318836ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:34.774864Z","caller":"traceutil/trace.go:171","msg":"trace[1683316195] linearizableReadLoop","detail":"{readStateIndex:27471; appliedIndex:27471; }","duration":"118.532555ms","start":"2026-06-30T09:55:34.656289Z","end":"2026-06-30T09:55:34.774822Z","steps":["trace[1683316195] 'read index received' (duration: 118.515764ms)","trace[1683316195] 'applied index is now lower than readState.Index' (duration: 13.75µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:55:34.775148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.831284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:55:34.77522Z","caller":"traceutil/trace.go:171","msg":"trace[948759698] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:26606; }","duration":"118.915266ms","start":"2026-06-30T09:55:34.656269Z","end":"2026-06-30T09:55:34.775184Z","steps":["trace[948759698] 'agreement among raft nodes before linearized reading' (duration: 118.682829ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:55:34.995545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.13465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T09:55:34.995815Z","caller":"traceutil/trace.go:171","msg":"trace[107009019] transaction","detail":"{read_only:false; response_revision:26607; number_of_response:1; }","duration":"279.712725ms","start":"2026-06-30T09:55:34.716046Z","end":"2026-06-30T09:55:34.995758Z","steps":["trace[107009019] 'process raft request' (duration: 158.213223ms)","trace[107009019] 'compare' (duration: 120.917944ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:55:34.996194Z","caller":"traceutil/trace.go:171","msg":"trace[1711794026] transaction","detail":"{read_only:false; response_revision:26608; number_of_response:1; }","duration":"176.830538ms","start":"2026-06-30T09:55:34.819352Z","end":"2026-06-30T09:55:34.996183Z","steps":["trace[1711794026] 'process raft request' (duration: 176.369085ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:34.996524Z","caller":"traceutil/trace.go:171","msg":"trace[1104527024] transaction","detail":"{read_only:false; response_revision:26609; number_of_response:1; }","duration":"159.483131ms","start":"2026-06-30T09:55:34.837007Z","end":"2026-06-30T09:55:34.99649Z","steps":["trace[1104527024] 'process raft request' (duration: 158.820962ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:34.996682Z","caller":"traceutil/trace.go:171","msg":"trace[1076181112] transaction","detail":"{read_only:false; response_revision:26610; number_of_response:1; }","duration":"158.463502ms","start":"2026-06-30T09:55:34.838206Z","end":"2026-06-30T09:55:34.996669Z","steps":["trace[1076181112] 'process raft request' (duration: 157.70152ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:35.239797Z","caller":"traceutil/trace.go:171","msg":"trace[1902918497] transaction","detail":"{read_only:false; response_revision:26612; number_of_response:1; }","duration":"156.70515ms","start":"2026-06-30T09:55:35.083053Z","end":"2026-06-30T09:55:35.239758Z","steps":["trace[1902918497] 'process raft request' (duration: 156.491234ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:35.415991Z","caller":"traceutil/trace.go:171","msg":"trace[261202291] transaction","detail":"{read_only:false; response_revision:26614; number_of_response:1; }","duration":"332.052155ms","start":"2026-06-30T09:55:35.083905Z","end":"2026-06-30T09:55:35.415957Z","steps":["trace[261202291] 'process raft request' (duration: 331.943062ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:35.416031Z","caller":"traceutil/trace.go:171","msg":"trace[1441213500] transaction","detail":"{read_only:false; response_revision:26613; number_of_response:1; }","duration":"332.250351ms","start":"2026-06-30T09:55:35.083742Z","end":"2026-06-30T09:55:35.415992Z","steps":["trace[1441213500] 'process raft request' (duration: 279.659363ms)","trace[1441213500] 'compare' (duration: 52.281559ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:55:35.416212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:55:35.08387Z","time spent":"332.22366ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T09:55:35.416364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:55:35.083722Z","time spent":"332.498478ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T09:55:35.41634Z","caller":"traceutil/trace.go:171","msg":"trace[766423074] linearizableReadLoop","detail":"{readStateIndex:27480; appliedIndex:27477; }","duration":"326.986827ms","start":"2026-06-30T09:55:35.08931Z","end":"2026-06-30T09:55:35.416297Z","steps":["trace[766423074] 'read index received' (duration: 150.603542ms)","trace[766423074] 'applied index is now lower than readState.Index' (duration: 176.381745ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:55:35.416639Z","caller":"traceutil/trace.go:171","msg":"trace[438133415] transaction","detail":"{read_only:false; response_revision:26615; number_of_response:1; }","duration":"332.681773ms","start":"2026-06-30T09:55:35.083914Z","end":"2026-06-30T09:55:35.416596Z","steps":["trace[438133415] 'process raft request' (duration: 331.993023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:55:35.41666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.170422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:55:35.417066Z","caller":"traceutil/trace.go:171","msg":"trace[181525440] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26615; }","duration":"327.741508ms","start":"2026-06-30T09:55:35.089279Z","end":"2026-06-30T09:55:35.417021Z","steps":["trace[181525440] 'agreement among raft nodes before linearized reading' (duration: 327.131481ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:55:35.417166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:55:35.08927Z","time spent":"327.844282ms","remote":"127.0.0.1:58978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-30T09:55:35.41792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:55:35.083882Z","time spent":"333.161557ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T09:55:35.66786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.065238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T09:55:35.668074Z","caller":"traceutil/trace.go:171","msg":"trace[1629679672] transaction","detail":"{read_only:false; response_revision:26616; number_of_response:1; }","duration":"241.55934ms","start":"2026-06-30T09:55:35.426458Z","end":"2026-06-30T09:55:35.668017Z","steps":["trace[1629679672] 'process raft request' (duration: 94.247144ms)","trace[1629679672] 'compare' (duration: 146.900273ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:55:35.668088Z","caller":"traceutil/trace.go:171","msg":"trace[1783265176] linearizableReadLoop","detail":"{readStateIndex:27481; appliedIndex:27480; }","duration":"241.374265ms","start":"2026-06-30T09:55:35.426682Z","end":"2026-06-30T09:55:35.668057Z","steps":["trace[1783265176] 'read index received' (duration: 93.928606ms)","trace[1783265176] 'applied index is now lower than readState.Index' (duration: 147.443579ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:55:35.946336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.615087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18557"} {"level":"info","ts":"2026-06-30T09:55:35.946482Z","caller":"traceutil/trace.go:171","msg":"trace[1434288288] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26616; }","duration":"519.795272ms","start":"2026-06-30T09:55:35.426656Z","end":"2026-06-30T09:55:35.946452Z","steps":["trace[1434288288] 'agreement among raft nodes before linearized reading' (duration: 241.498939ms)","trace[1434288288] 'range keys from in-memory index tree' (duration: 277.643804ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T09:55:35.946545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:55:35.426651Z","time spent":"519.871464ms","remote":"127.0.0.1:58670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18581,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-30T09:55:35.947786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.799136ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-06-30T09:55:36.025363Z","caller":"traceutil/trace.go:171","msg":"trace[2130737075] transaction","detail":"{read_only:false; response_revision:26617; number_of_response:1; }","duration":"305.310673ms","start":"2026-06-30T09:55:35.720009Z","end":"2026-06-30T09:55:36.025319Z","steps":["trace[2130737075] 'process raft request' (duration: 305.026135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:55:36.025639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:55:35.719978Z","time spent":"305.472448ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T09:55:36.102206Z","caller":"traceutil/trace.go:171","msg":"trace[1536700827] linearizableReadLoop","detail":"{readStateIndex:27484; appliedIndex:27483; }","duration":"190.876099ms","start":"2026-06-30T09:55:35.911294Z","end":"2026-06-30T09:55:36.10217Z","steps":["trace[1536700827] 'read index received' (duration: 114.29686ms)","trace[1536700827] 'applied index is now lower than readState.Index' (duration: 76.577659ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:55:36.10248Z","caller":"traceutil/trace.go:171","msg":"trace[237632662] transaction","detail":"{read_only:false; response_revision:26618; number_of_response:1; }","duration":"381.794899ms","start":"2026-06-30T09:55:35.720662Z","end":"2026-06-30T09:55:36.102457Z","steps":["trace[237632662] 'process raft request' (duration: 372.695712ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:55:36.102768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.646909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"warn","ts":"2026-06-30T09:55:36.102795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.449587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T09:55:36.102852Z","caller":"traceutil/trace.go:171","msg":"trace[7505691] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26618; }","duration":"142.797133ms","start":"2026-06-30T09:55:35.960035Z","end":"2026-06-30T09:55:36.102832Z","steps":["trace[7505691] 'agreement among raft nodes before linearized reading' (duration: 142.537365ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:36.102878Z","caller":"traceutil/trace.go:171","msg":"trace[87090581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26618; }","duration":"138.577631ms","start":"2026-06-30T09:55:35.964279Z","end":"2026-06-30T09:55:36.102857Z","steps":["trace[87090581] 'agreement among raft nodes before linearized reading' (duration: 138.417726ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T09:55:36.102775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T09:55:35.720635Z","time spent":"381.900352ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T09:55:36.104992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.658781ms","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-06-30T09:55:36.105207Z","caller":"traceutil/trace.go:171","msg":"trace[554254865] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26618; }","duration":"193.822946ms","start":"2026-06-30T09:55:35.911257Z","end":"2026-06-30T09:55:36.10508Z","steps":["trace[554254865] 'agreement among raft nodes before linearized reading' (duration: 191.426616ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:55:45.15222Z","caller":"traceutil/trace.go:171","msg":"trace[1891769809] transaction","detail":"{read_only:false; response_revision:26683; number_of_response:1; }","duration":"112.92619ms","start":"2026-06-30T09:55:45.03926Z","end":"2026-06-30T09:55:45.152186Z","steps":["trace[1891769809] 'process raft request' (duration: 92.204655ms)","trace[1891769809] 'compare' (duration: 20.511199ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T09:55:45.152478Z","caller":"traceutil/trace.go:171","msg":"trace[959462244] transaction","detail":"{read_only:false; response_revision:26684; number_of_response:1; }","duration":"113.027473ms","start":"2026-06-30T09:55:45.039377Z","end":"2026-06-30T09:55:45.152404Z","steps":["trace[959462244] 'process raft request' (duration: 112.756036ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T09:57:57.437514Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25145} {"level":"info","ts":"2026-06-30T09:57:57.602786Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25145,"took":"160.960414ms","hash":3423138499,"current-db-size-bytes":59211776,"current-db-size":"59 MB","current-db-size-in-use-bytes":37560320,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-06-30T09:57:57.602872Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3423138499,"revision":25145,"compact-revision":21978} {"level":"info","ts":"2026-06-30T09:59:47.522033Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ad427454a89beddc","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-30T09:59:47.527856Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-30T09:59:47.527987Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-30T10:02:57.444958Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27954} {"level":"info","ts":"2026-06-30T10:02:57.585974Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27954,"took":"136.756877ms","hash":3024281038,"current-db-size-bytes":59211776,"current-db-size":"59 MB","current-db-size-in-use-bytes":33697792,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-30T10:02:57.586047Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3024281038,"revision":27954,"compact-revision":25145} {"level":"info","ts":"2026-06-30T10:07:57.455071Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30585} {"level":"info","ts":"2026-06-30T10:07:57.591243Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30585,"took":"132.358309ms","hash":231161928,"current-db-size-bytes":59211776,"current-db-size":"59 MB","current-db-size-in-use-bytes":30900224,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-30T10:07:57.591341Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":231161928,"revision":30585,"compact-revision":27954} {"level":"info","ts":"2026-06-30T10:12:57.464716Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32867} {"level":"info","ts":"2026-06-30T10:12:57.60557Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32867,"took":"136.136884ms","hash":1363253124,"current-db-size-bytes":59211776,"current-db-size":"59 MB","current-db-size-in-use-bytes":30396416,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-30T10:12:57.605618Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1363253124,"revision":32867,"compact-revision":30585} {"level":"info","ts":"2026-06-30T10:17:57.472646Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35099} {"level":"info","ts":"2026-06-30T10:17:57.623311Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35099,"took":"145.682323ms","hash":2899281592,"current-db-size-bytes":59211776,"current-db-size":"59 MB","current-db-size-in-use-bytes":31735808,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-30T10:17:57.623397Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2899281592,"revision":35099,"compact-revision":32867} {"level":"info","ts":"2026-06-30T10:20:59.452692Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ad427454a89beddc","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-30T10:20:59.457631Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-30T10:20:59.457754Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-30T10:22:57.478562Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37391} {"level":"info","ts":"2026-06-30T10:22:57.616643Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37391,"took":"130.757128ms","hash":4218490680,"current-db-size-bytes":59211776,"current-db-size":"59 MB","current-db-size-in-use-bytes":33009664,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-30T10:22:57.616717Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4218490680,"revision":37391,"compact-revision":35099} {"level":"info","ts":"2026-06-30T10:27:57.48412Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39669} {"level":"info","ts":"2026-06-30T10:27:57.60852Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39669,"took":"120.339243ms","hash":3940831348,"current-db-size-bytes":59211776,"current-db-size":"59 MB","current-db-size-in-use-bytes":31768576,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-30T10:27:57.608587Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3940831348,"revision":39669,"compact-revision":37391}