{"level":"warn","ts":"2026-09-16T21:52:44.683565Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-16T21:52:44.683686Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.27: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://162.253.55.27:2380","--initial-cluster=instance=https://162.253.55.27:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.27:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.27: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-09-16T21:52:44.683772Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-16T21:52:44.683789Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.27:2380"]} {"level":"info","ts":"2026-09-16T21:52:44.683814Z","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-09-16T21:52:44.684502Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"]} {"level":"info","ts":"2026-09-16T21:52:44.684593Z","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://162.253.55.27:2380"],"listen-peer-urls":["https://162.253.55.27:2380"],"advertise-client-urls":["https://162.253.55.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.27: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-09-16T21:52:44.689461Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.61585ms"} {"level":"info","ts":"2026-09-16T21:52:44.6963Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d9b565cb482924cf","cluster-id":"5bc3f8d4cf836b6c"} {"level":"info","ts":"2026-09-16T21:52:44.696395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=()"} {"level":"info","ts":"2026-09-16T21:52:44.696414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became follower at term 0"} {"level":"info","ts":"2026-09-16T21:52:44.696423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d9b565cb482924cf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-16T21:52:44.696435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became follower at term 1"} {"level":"info","ts":"2026-09-16T21:52:44.696479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=(15687556800778413263)"} {"level":"warn","ts":"2026-09-16T21:52:44.71154Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-16T21:52:44.713293Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-16T21:52:44.715492Z","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-09-16T21:52:44.717427Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d9b565cb482924cf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-16T21:52:44.717616Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d9b565cb482924cf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-16T21:52:44.717711Z","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-09-16T21:52:44.717877Z","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-09-16T21:52:44.717898Z","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-09-16T21:52:44.719759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=(15687556800778413263)"} {"level":"info","ts":"2026-09-16T21:52:44.721015Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5bc3f8d4cf836b6c","local-member-id":"d9b565cb482924cf","added-peer-id":"d9b565cb482924cf","added-peer-peer-urls":["https://162.253.55.27:2380"]} {"level":"info","ts":"2026-09-16T21:52:44.723321Z","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-09-16T21:52:44.723467Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.27:2380"} {"level":"info","ts":"2026-09-16T21:52:44.723519Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.27:2380"} {"level":"info","ts":"2026-09-16T21:52:44.723963Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d9b565cb482924cf","initial-advertise-peer-urls":["https://162.253.55.27:2380"],"listen-peer-urls":["https://162.253.55.27:2380"],"advertise-client-urls":["https://162.253.55.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-16T21:52:44.724074Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-16T21:52:45.397829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf is starting a new election at term 1"} {"level":"info","ts":"2026-09-16T21:52:45.397933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became pre-candidate at term 1"} {"level":"info","ts":"2026-09-16T21:52:45.397993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf received MsgPreVoteResp from d9b565cb482924cf at term 1"} {"level":"info","ts":"2026-09-16T21:52:45.39803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became candidate at term 2"} {"level":"info","ts":"2026-09-16T21:52:45.398051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf received MsgVoteResp from d9b565cb482924cf at term 2"} {"level":"info","ts":"2026-09-16T21:52:45.398077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became leader at term 2"} {"level":"info","ts":"2026-09-16T21:52:45.398101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d9b565cb482924cf elected leader d9b565cb482924cf at term 2"} {"level":"info","ts":"2026-09-16T21:52:45.450987Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d9b565cb482924cf","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.27:2379]}","request-path":"/0/members/d9b565cb482924cf/attributes","cluster-id":"5bc3f8d4cf836b6c","publish-timeout":"7s"} {"level":"info","ts":"2026-09-16T21:52:45.451002Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-16T21:52:45.451036Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-16T21:52:45.451063Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-16T21:52:45.45131Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-16T21:52:45.451438Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-16T21:52:45.452471Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5bc3f8d4cf836b6c","local-member-id":"d9b565cb482924cf","cluster-version":"3.5"} {"level":"info","ts":"2026-09-16T21:52:45.452641Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-16T21:52:45.4527Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-16T21:52:45.453295Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.27:2379"} {"level":"info","ts":"2026-09-16T21:52:45.453839Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-16T21:52:59.098627Z","caller":"traceutil/trace.go:171","msg":"trace[1097345988] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"126.004907ms","start":"2026-09-16T21:52:58.97258Z","end":"2026-09-16T21:52:59.098585Z","steps":["trace[1097345988] 'process raft request' (duration: 125.737441ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:53:25.388768Z","caller":"traceutil/trace.go:171","msg":"trace[1197674767] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"133.162178ms","start":"2026-09-16T21:53:25.255577Z","end":"2026-09-16T21:53:25.388739Z","steps":["trace[1197674767] 'process raft request' (duration: 132.970534ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:53:25.389529Z","caller":"traceutil/trace.go:171","msg":"trace[653739328] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"130.86416ms","start":"2026-09-16T21:53:25.258644Z","end":"2026-09-16T21:53:25.389508Z","steps":["trace[653739328] 'process raft request' (duration: 130.726437ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:53:31.854947Z","caller":"traceutil/trace.go:171","msg":"trace[1678743330] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"108.860167ms","start":"2026-09-16T21:53:31.746055Z","end":"2026-09-16T21:53:31.854915Z","steps":["trace[1678743330] 'process raft request' (duration: 105.448333ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:53:32.300638Z","caller":"traceutil/trace.go:171","msg":"trace[1917944958] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"199.154525ms","start":"2026-09-16T21:53:32.101456Z","end":"2026-09-16T21:53:32.30061Z","steps":["trace[1917944958] 'process raft request' (duration: 198.596983ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:53:32.78672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.101951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-16T21:53:32.786955Z","caller":"traceutil/trace.go:171","msg":"trace[984473890] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"415.363095ms","start":"2026-09-16T21:53:32.371542Z","end":"2026-09-16T21:53:32.786905Z","steps":["trace[984473890] 'process raft request' (duration: 312.558319ms)","trace[984473890] 'compare' (duration: 101.826326ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:53:32.7871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:53:32.371521Z","time spent":"415.513389ms","remote":"127.0.0.1:52436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-16T21:53:34.286799Z","caller":"traceutil/trace.go:171","msg":"trace[688046115] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"112.919435ms","start":"2026-09-16T21:53:34.173839Z","end":"2026-09-16T21:53:34.286759Z","steps":["trace[688046115] 'process raft request' (duration: 105.783531ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:55:02.802037Z","caller":"traceutil/trace.go:171","msg":"trace[227332085] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1935; }","duration":"179.185786ms","start":"2026-09-16T21:55:02.62283Z","end":"2026-09-16T21:55:02.802016Z","steps":["trace[227332085] 'read index received' (duration: 178.90165ms)","trace[227332085] 'applied index is now lower than readState.Index' (duration: 283.266µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:55:02.802145Z","caller":"traceutil/trace.go:171","msg":"trace[1335427784] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"267.115523ms","start":"2026-09-16T21:55:02.535005Z","end":"2026-09-16T21:55:02.802121Z","steps":["trace[1335427784] 'process raft request' (duration: 266.821187ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:55:02.8022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.354389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T21:55:02.802237Z","caller":"traceutil/trace.go:171","msg":"trace[824426873] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1865; }","duration":"179.488203ms","start":"2026-09-16T21:55:02.622735Z","end":"2026-09-16T21:55:02.802223Z","steps":["trace[824426873] 'agreement among raft nodes before linearized reading' (duration: 179.401691ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:55:02.802355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.532781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-16T21:55:02.802417Z","caller":"traceutil/trace.go:171","msg":"trace[2117078137] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1865; }","duration":"109.640283ms","start":"2026-09-16T21:55:02.692763Z","end":"2026-09-16T21:55:02.802403Z","steps":["trace[2117078137] 'agreement among raft nodes before linearized reading' (duration: 109.448279ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:55:02.92944Z","caller":"traceutil/trace.go:171","msg":"trace[622105883] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1936; }","duration":"105.014434ms","start":"2026-09-16T21:55:02.824397Z","end":"2026-09-16T21:55:02.929412Z","steps":["trace[622105883] 'read index received' (duration: 46.254422ms)","trace[622105883] 'applied index is now lower than readState.Index' (duration: 58.758902ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:55:02.929464Z","caller":"traceutil/trace.go:171","msg":"trace[1511582715] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"121.729773ms","start":"2026-09-16T21:55:02.807701Z","end":"2026-09-16T21:55:02.929431Z","steps":["trace[1511582715] 'process raft request' (duration: 62.942691ms)","trace[1511582715] 'compare' (duration: 58.582258ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:55:02.929683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.269719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d9e203cd-92d4-44cd-a40f-a7efff67f419\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-09-16T21:55:02.929726Z","caller":"traceutil/trace.go:171","msg":"trace[466274401] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d9e203cd-92d4-44cd-a40f-a7efff67f419; range_end:; response_count:1; response_revision:1866; }","duration":"105.365051ms","start":"2026-09-16T21:55:02.824347Z","end":"2026-09-16T21:55:02.929712Z","steps":["trace[466274401] 'agreement among raft nodes before linearized reading' (duration: 105.170127ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:00:29.222969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.451436ms","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-09-16T22:00:29.223045Z","caller":"traceutil/trace.go:171","msg":"trace[1975813244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4057; }","duration":"115.587879ms","start":"2026-09-16T22:00:29.107441Z","end":"2026-09-16T22:00:29.223029Z","steps":["trace[1975813244] 'range keys from in-memory index tree' (duration: 115.226611ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:01:54.996955Z","caller":"traceutil/trace.go:171","msg":"trace[40987584] transaction","detail":"{read_only:false; response_revision:5035; number_of_response:1; }","duration":"122.455936ms","start":"2026-09-16T22:01:54.874379Z","end":"2026-09-16T22:01:54.996835Z","steps":["trace[40987584] 'process raft request' (duration: 122.043317ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:02:00.016628Z","caller":"traceutil/trace.go:171","msg":"trace[743088232] transaction","detail":"{read_only:false; response_revision:5146; number_of_response:1; }","duration":"139.797349ms","start":"2026-09-16T22:01:59.876797Z","end":"2026-09-16T22:02:00.016594Z","steps":["trace[743088232] 'process raft request' (duration: 139.550904ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:02:01.047678Z","caller":"traceutil/trace.go:171","msg":"trace[1768135016] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"125.003691ms","start":"2026-09-16T22:02:00.922649Z","end":"2026-09-16T22:02:01.047652Z","steps":["trace[1768135016] 'process raft request' (duration: 124.842047ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:02:02.601419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.35206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-578cfbd5-17e0-4903-8d65-705e805fb09c\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-09-16T22:02:02.601533Z","caller":"traceutil/trace.go:171","msg":"trace[740644616] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-578cfbd5-17e0-4903-8d65-705e805fb09c; range_end:; response_count:1; response_revision:5262; }","duration":"181.502263ms","start":"2026-09-16T22:02:02.419988Z","end":"2026-09-16T22:02:02.60149Z","steps":["trace[740644616] 'range keys from in-memory index tree' (duration: 181.122765ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:02:17.107957Z","caller":"traceutil/trace.go:171","msg":"trace[1939162883] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"111.497532ms","start":"2026-09-16T22:02:16.996399Z","end":"2026-09-16T22:02:17.107896Z","steps":["trace[1939162883] 'process raft request' (duration: 94.12775ms)","trace[1939162883] 'compare' (duration: 17.154557ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:02:17.503943Z","caller":"traceutil/trace.go:171","msg":"trace[64302529] transaction","detail":"{read_only:false; response_revision:5526; number_of_response:1; }","duration":"122.225512ms","start":"2026-09-16T22:02:17.381694Z","end":"2026-09-16T22:02:17.50392Z","steps":["trace[64302529] 'process raft request' (duration: 122.15404ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:02:17.504296Z","caller":"traceutil/trace.go:171","msg":"trace[911509732] transaction","detail":"{read_only:false; response_revision:5525; number_of_response:1; }","duration":"152.335998ms","start":"2026-09-16T22:02:17.351925Z","end":"2026-09-16T22:02:17.504261Z","steps":["trace[911509732] 'process raft request' (duration: 96.920379ms)","trace[911509732] 'compare' (duration: 54.630511ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:02:45.481972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2999} {"level":"info","ts":"2026-09-16T22:02:45.567972Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2999,"took":"84.599775ms","hash":2479960130,"current-db-size-bytes":55230464,"current-db-size":"55 MB","current-db-size-in-use-bytes":31928320,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-16T22:02:45.56804Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2479960130,"revision":2999,"compact-revision":-1} {"level":"info","ts":"2026-09-16T22:03:17.988159Z","caller":"traceutil/trace.go:171","msg":"trace[500305612] transaction","detail":"{read_only:false; response_revision:6207; number_of_response:1; }","duration":"154.131946ms","start":"2026-09-16T22:03:17.834003Z","end":"2026-09-16T22:03:17.988135Z","steps":["trace[500305612] 'process raft request' (duration: 153.966082ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:07:45.489782Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5917} {"level":"info","ts":"2026-09-16T22:07:45.579041Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5917,"took":"87.424865ms","hash":1203339029,"current-db-size-bytes":55230464,"current-db-size":"55 MB","current-db-size-in-use-bytes":25268224,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-16T22:07:45.57909Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1203339029,"revision":5917,"compact-revision":2999} {"level":"warn","ts":"2026-09-16T22:08:42.676097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.859083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T22:08:42.676238Z","caller":"traceutil/trace.go:171","msg":"trace[221244548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8590; }","duration":"170.035647ms","start":"2026-09-16T22:08:42.506163Z","end":"2026-09-16T22:08:42.676198Z","steps":["trace[221244548] 'range keys from in-memory index tree' (duration: 169.774552ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:09:11.657669Z","caller":"traceutil/trace.go:171","msg":"trace[2047638451] transaction","detail":"{read_only:false; response_revision:8799; number_of_response:1; }","duration":"123.013838ms","start":"2026-09-16T22:09:11.534625Z","end":"2026-09-16T22:09:11.657639Z","steps":["trace[2047638451] 'process raft request' (duration: 122.526308ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:09:17.792369Z","caller":"traceutil/trace.go:171","msg":"trace[1648432639] linearizableReadLoop","detail":"{readStateIndex:9146; appliedIndex:9145; }","duration":"110.679142ms","start":"2026-09-16T22:09:17.681661Z","end":"2026-09-16T22:09:17.79234Z","steps":["trace[1648432639] 'read index received' (duration: 110.531089ms)","trace[1648432639] 'applied index is now lower than readState.Index' (duration: 146.953µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:09:17.792434Z","caller":"traceutil/trace.go:171","msg":"trace[1468697723] transaction","detail":"{read_only:false; response_revision:8830; number_of_response:1; }","duration":"113.059434ms","start":"2026-09-16T22:09:17.679343Z","end":"2026-09-16T22:09:17.792403Z","steps":["trace[1468697723] 'process raft request' (duration: 112.802328ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:09:17.792601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.898678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T22:09:17.792694Z","caller":"traceutil/trace.go:171","msg":"trace[872702394] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:8830; }","duration":"111.069251ms","start":"2026-09-16T22:09:17.681604Z","end":"2026-09-16T22:09:17.792674Z","steps":["trace[872702394] 'agreement among raft nodes before linearized reading' (duration: 110.862547ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:09:17.805877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.27587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T22:09:17.805923Z","caller":"traceutil/trace.go:171","msg":"trace[780594151] transaction","detail":"{read_only:false; response_revision:8831; number_of_response:1; }","duration":"100.800632ms","start":"2026-09-16T22:09:17.705068Z","end":"2026-09-16T22:09:17.805869Z","steps":["trace[780594151] 'process raft request' (duration: 100.475325ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:09:17.805963Z","caller":"traceutil/trace.go:171","msg":"trace[1509583801] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:8831; }","duration":"107.416783ms","start":"2026-09-16T22:09:17.698531Z","end":"2026-09-16T22:09:17.805947Z","steps":["trace[1509583801] 'agreement among raft nodes before linearized reading' (duration: 107.258019ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:09:18.672548Z","caller":"traceutil/trace.go:171","msg":"trace[2031583018] linearizableReadLoop","detail":"{readStateIndex:9152; appliedIndex:9151; }","duration":"218.189708ms","start":"2026-09-16T22:09:18.454334Z","end":"2026-09-16T22:09:18.672523Z","steps":["trace[2031583018] 'read index received' (duration: 203.130275ms)","trace[2031583018] 'applied index is now lower than readState.Index' (duration: 15.058463ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:09:18.672668Z","caller":"traceutil/trace.go:171","msg":"trace[1661196832] transaction","detail":"{read_only:false; response_revision:8836; number_of_response:1; }","duration":"220.662912ms","start":"2026-09-16T22:09:18.451993Z","end":"2026-09-16T22:09:18.672656Z","steps":["trace[1661196832] 'process raft request' (duration: 205.492026ms)","trace[1661196832] 'compare' (duration: 14.92168ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:09:18.672748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.909974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T22:09:18.672797Z","caller":"traceutil/trace.go:171","msg":"trace[1318048627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8836; }","duration":"163.013876ms","start":"2026-09-16T22:09:18.509771Z","end":"2026-09-16T22:09:18.672785Z","steps":["trace[1318048627] 'agreement among raft nodes before linearized reading' (duration: 162.936874ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:09:18.67313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.662804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tzmm6\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-09-16T22:09:18.673193Z","caller":"traceutil/trace.go:171","msg":"trace[1943457910] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tzmm6; range_end:; response_count:1; response_revision:8836; }","duration":"145.765365ms","start":"2026-09-16T22:09:18.527412Z","end":"2026-09-16T22:09:18.673177Z","steps":["trace[1943457910] 'agreement among raft nodes before linearized reading' (duration: 145.53328ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:09:18.67326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.900853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-16T22:09:18.673333Z","caller":"traceutil/trace.go:171","msg":"trace[179362143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8836; }","duration":"219.002616ms","start":"2026-09-16T22:09:18.454313Z","end":"2026-09-16T22:09:18.673315Z","steps":["trace[179362143] 'agreement among raft nodes before linearized reading' (duration: 218.591678ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:09:25.018773Z","caller":"traceutil/trace.go:171","msg":"trace[1611749918] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8907; }","duration":"120.818481ms","start":"2026-09-16T22:09:24.897925Z","end":"2026-09-16T22:09:25.018744Z","steps":["trace[1611749918] 'process raft request' (duration: 110.349496ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:09:25.04121Z","caller":"traceutil/trace.go:171","msg":"trace[221542070] transaction","detail":"{read_only:false; response_revision:8908; number_of_response:1; }","duration":"103.231164ms","start":"2026-09-16T22:09:24.937933Z","end":"2026-09-16T22:09:25.041164Z","steps":["trace[221542070] 'process raft request' (duration: 103.088901ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:09:25.808301Z","caller":"traceutil/trace.go:171","msg":"trace[1027628574] transaction","detail":"{read_only:false; response_revision:8916; number_of_response:1; }","duration":"153.841789ms","start":"2026-09-16T22:09:25.654432Z","end":"2026-09-16T22:09:25.808274Z","steps":["trace[1027628574] 'process raft request' (duration: 153.668405ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:09:58.713092Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002569.wal"} {"level":"info","ts":"2026-09-16T22:10:41.085623Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d9b565cb482924cf","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-16T22:10:41.094041Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-16T22:10:41.094215Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-16T22:12:45.504837Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8082} {"level":"info","ts":"2026-09-16T22:12:45.578535Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8082,"took":"69.144271ms","hash":3284399898,"current-db-size-bytes":55230464,"current-db-size":"55 MB","current-db-size-in-use-bytes":28798976,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-16T22:12:45.578628Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3284399898,"revision":8082,"compact-revision":5917} {"level":"info","ts":"2026-09-16T22:13:14.963284Z","caller":"traceutil/trace.go:171","msg":"trace[432860830] transaction","detail":"{read_only:false; response_revision:10823; number_of_response:1; }","duration":"207.75756ms","start":"2026-09-16T22:13:14.755501Z","end":"2026-09-16T22:13:14.963259Z","steps":["trace[432860830] 'process raft request' (duration: 207.602097ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:13:23.519753Z","caller":"traceutil/trace.go:171","msg":"trace[546102969] transaction","detail":"{read_only:false; response_revision:10939; number_of_response:1; }","duration":"162.347108ms","start":"2026-09-16T22:13:23.357377Z","end":"2026-09-16T22:13:23.519724Z","steps":["trace[546102969] 'process raft request' (duration: 162.099193ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:13:26.044415Z","caller":"traceutil/trace.go:171","msg":"trace[1735931299] transaction","detail":"{read_only:false; response_revision:10964; number_of_response:1; }","duration":"134.41778ms","start":"2026-09-16T22:13:25.909968Z","end":"2026-09-16T22:13:26.044386Z","steps":["trace[1735931299] 'process raft request' (duration: 134.247856ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:13:26.058804Z","caller":"traceutil/trace.go:171","msg":"trace[1333534280] transaction","detail":"{read_only:false; response_revision:10965; number_of_response:1; }","duration":"148.653695ms","start":"2026-09-16T22:13:25.910121Z","end":"2026-09-16T22:13:26.058774Z","steps":["trace[1333534280] 'process raft request' (duration: 148.476761ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:13:26.335355Z","caller":"traceutil/trace.go:171","msg":"trace[1628873808] transaction","detail":"{read_only:false; response_revision:10969; number_of_response:1; }","duration":"110.66436ms","start":"2026-09-16T22:13:26.22466Z","end":"2026-09-16T22:13:26.335324Z","steps":["trace[1628873808] 'process raft request' (duration: 110.596569ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:13:26.3354Z","caller":"traceutil/trace.go:171","msg":"trace[818912934] transaction","detail":"{read_only:false; response_revision:10968; number_of_response:1; }","duration":"126.758075ms","start":"2026-09-16T22:13:26.208578Z","end":"2026-09-16T22:13:26.335336Z","steps":["trace[818912934] 'process raft request' (duration: 88.567528ms)","trace[818912934] 'compare' (duration: 37.974763ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:13:26.470403Z","caller":"traceutil/trace.go:171","msg":"trace[45337135] transaction","detail":"{read_only:false; response_revision:10971; number_of_response:1; }","duration":"126.055481ms","start":"2026-09-16T22:13:26.344314Z","end":"2026-09-16T22:13:26.470369Z","steps":["trace[45337135] 'process raft request' (duration: 95.942436ms)","trace[45337135] 'compare' (duration: 29.90589ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:13:27.963799Z","caller":"traceutil/trace.go:171","msg":"trace[78372684] transaction","detail":"{read_only:false; response_revision:10978; number_of_response:1; }","duration":"118.863847ms","start":"2026-09-16T22:13:27.844902Z","end":"2026-09-16T22:13:27.963766Z","steps":["trace[78372684] 'process raft request' (duration: 118.390316ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:13:28.223292Z","caller":"traceutil/trace.go:171","msg":"trace[1982581513] transaction","detail":"{read_only:false; response_revision:10981; number_of_response:1; }","duration":"125.422827ms","start":"2026-09-16T22:13:28.09783Z","end":"2026-09-16T22:13:28.223253Z","steps":["trace[1982581513] 'process raft request' (duration: 94.863462ms)","trace[1982581513] 'compare' (duration: 30.31953ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:13:28.223537Z","caller":"traceutil/trace.go:171","msg":"trace[2076715122] linearizableReadLoop","detail":"{readStateIndex:11364; appliedIndex:11362; }","duration":"120.947181ms","start":"2026-09-16T22:13:28.102565Z","end":"2026-09-16T22:13:28.223513Z","steps":["trace[2076715122] 'read index received' (duration: 80.926944ms)","trace[2076715122] 'applied index is now lower than readState.Index' (duration: 40.019307ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:13:28.223726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.136055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-16T22:13:28.223778Z","caller":"traceutil/trace.go:171","msg":"trace[1420302108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10982; }","duration":"121.228596ms","start":"2026-09-16T22:13:28.102534Z","end":"2026-09-16T22:13:28.223762Z","steps":["trace[1420302108] 'agreement among raft nodes before linearized reading' (duration: 121.067713ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:13:28.538303Z","caller":"traceutil/trace.go:171","msg":"trace[2110498591] transaction","detail":"{read_only:false; response_revision:10988; number_of_response:1; }","duration":"105.00758ms","start":"2026-09-16T22:13:28.433246Z","end":"2026-09-16T22:13:28.538253Z","steps":["trace[2110498591] 'process raft request' (duration: 104.775375ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:14:25.696474Z","caller":"traceutil/trace.go:171","msg":"trace[1011719360] transaction","detail":"{read_only:false; response_revision:11503; number_of_response:1; }","duration":"160.643431ms","start":"2026-09-16T22:14:25.535766Z","end":"2026-09-16T22:14:25.696409Z","steps":["trace[1011719360] 'process raft request' (duration: 145.237874ms)","trace[1011719360] 'compare' (duration: 14.989868ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:17:45.514619Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10552} {"level":"info","ts":"2026-09-16T22:17:45.609652Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10552,"took":"92.391412ms","hash":1864074149,"current-db-size-bytes":55230464,"current-db-size":"55 MB","current-db-size-in-use-bytes":27348992,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-16T22:17:45.609713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1864074149,"revision":10552,"compact-revision":8082} {"level":"info","ts":"2026-09-16T22:20:42.28216Z","caller":"traceutil/trace.go:171","msg":"trace[1197118881] linearizableReadLoop","detail":"{readStateIndex:14518; appliedIndex:14517; }","duration":"109.208315ms","start":"2026-09-16T22:20:42.172913Z","end":"2026-09-16T22:20:42.282121Z","steps":["trace[1197118881] 'read index received' (duration: 105.888194ms)","trace[1197118881] 'applied index is now lower than readState.Index' (duration: 3.318461ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:20:42.282248Z","caller":"traceutil/trace.go:171","msg":"trace[1262022055] transaction","detail":"{read_only:false; response_revision:14034; number_of_response:1; }","duration":"110.258137ms","start":"2026-09-16T22:20:42.171944Z","end":"2026-09-16T22:20:42.282202Z","steps":["trace[1262022055] 'process raft request' (duration: 106.907746ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:20:42.282511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.593414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/placement.18d5ed2de5057400\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-09-16T22:20:42.282571Z","caller":"traceutil/trace.go:171","msg":"trace[1325017862] range","detail":"{range_begin:/registry/events/openstack/placement.18d5ed2de5057400; range_end:; response_count:1; response_revision:14034; }","duration":"109.720057ms","start":"2026-09-16T22:20:42.172836Z","end":"2026-09-16T22:20:42.282556Z","steps":["trace[1325017862] 'agreement among raft nodes before linearized reading' (duration: 109.483281ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:21:00.402122Z","caller":"traceutil/trace.go:171","msg":"trace[813201503] transaction","detail":"{read_only:false; response_revision:14149; number_of_response:1; }","duration":"276.123124ms","start":"2026-09-16T22:21:00.125976Z","end":"2026-09-16T22:21:00.402099Z","steps":["trace[813201503] 'process raft request' (duration: 275.978991ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:21:00.698258Z","caller":"traceutil/trace.go:171","msg":"trace[421854298] transaction","detail":"{read_only:false; response_revision:14151; number_of_response:1; }","duration":"268.743006ms","start":"2026-09-16T22:21:00.429464Z","end":"2026-09-16T22:21:00.698207Z","steps":["trace[421854298] 'process raft request' (duration: 207.001916ms)","trace[421854298] 'compare' (duration: 61.573536ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:21:00.698272Z","caller":"traceutil/trace.go:171","msg":"trace[1687765370] linearizableReadLoop","detail":"{readStateIndex:14640; appliedIndex:14639; }","duration":"192.464485ms","start":"2026-09-16T22:21:00.505785Z","end":"2026-09-16T22:21:00.698249Z","steps":["trace[1687765370] 'read index received' (duration: 130.714425ms)","trace[1687765370] 'applied index is now lower than readState.Index' (duration: 61.74869ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:21:00.698396Z","caller":"traceutil/trace.go:171","msg":"trace[2028820175] transaction","detail":"{read_only:false; response_revision:14152; number_of_response:1; }","duration":"108.299054ms","start":"2026-09-16T22:21:00.590082Z","end":"2026-09-16T22:21:00.698381Z","steps":["trace[2028820175] 'process raft request' (duration: 108.09515ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:21:00.698626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.763441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T22:21:00.698716Z","caller":"traceutil/trace.go:171","msg":"trace[2005726970] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:14151; }","duration":"192.955536ms","start":"2026-09-16T22:21:00.505737Z","end":"2026-09-16T22:21:00.698692Z","steps":["trace[2005726970] 'agreement among raft nodes before linearized reading' (duration: 192.579607ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:22:44.656098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.732507ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-16T22:22:44.656223Z","caller":"traceutil/trace.go:171","msg":"trace[957073814] linearizableReadLoop","detail":"{readStateIndex:15765; appliedIndex:15764; }","duration":"200.321503ms","start":"2026-09-16T22:22:44.455882Z","end":"2026-09-16T22:22:44.656203Z","steps":["trace[957073814] 'read index received' (duration: 31.399192ms)","trace[957073814] 'applied index is now lower than readState.Index' (duration: 168.920881ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:22:44.656499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.032267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T22:22:44.656595Z","caller":"traceutil/trace.go:171","msg":"trace[1353920833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15240; }","duration":"150.17845ms","start":"2026-09-16T22:22:44.506399Z","end":"2026-09-16T22:22:44.656578Z","steps":["trace[1353920833] 'agreement among raft nodes before linearized reading' (duration: 150.038007ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:22:44.657614Z","caller":"traceutil/trace.go:171","msg":"trace[1580320709] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15240; }","duration":"100.013589ms","start":"2026-09-16T22:22:44.557589Z","end":"2026-09-16T22:22:44.657603Z","steps":["trace[1580320709] 'agreement among raft nodes before linearized reading' (duration: 99.990548ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:22:44.65772Z","caller":"traceutil/trace.go:171","msg":"trace[1726764118] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15240; }","duration":"100.03597ms","start":"2026-09-16T22:22:44.557673Z","end":"2026-09-16T22:22:44.657709Z","steps":["trace[1726764118] 'agreement among raft nodes before linearized reading' (duration: 100.02384ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:22:44.657844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.208583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-09-16T22:22:44.657876Z","caller":"traceutil/trace.go:171","msg":"trace[1786461326] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15240; }","duration":"100.243334ms","start":"2026-09-16T22:22:44.557625Z","end":"2026-09-16T22:22:44.657869Z","steps":["trace[1786461326] 'agreement among raft nodes before linearized reading' (duration: 100.152362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:22:44.659937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.030362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-16T22:22:44.660004Z","caller":"traceutil/trace.go:171","msg":"trace[1251028792] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:15240; }","duration":"204.159285ms","start":"2026-09-16T22:22:44.455829Z","end":"2026-09-16T22:22:44.659988Z","steps":["trace[1251028792] 'agreement among raft nodes before linearized reading' (duration: 200.473916ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:22:44.911669Z","caller":"traceutil/trace.go:171","msg":"trace[764730510] linearizableReadLoop","detail":"{readStateIndex:15766; appliedIndex:15765; }","duration":"100.677542ms","start":"2026-09-16T22:22:44.81097Z","end":"2026-09-16T22:22:44.911648Z","steps":["trace[764730510] 'read index received' (duration: 100.464348ms)","trace[764730510] 'applied index is now lower than readState.Index' (duration: 212.484µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:22:44.911704Z","caller":"traceutil/trace.go:171","msg":"trace[817283195] transaction","detail":"{read_only:false; response_revision:15241; number_of_response:1; }","duration":"144.473328ms","start":"2026-09-16T22:22:44.767217Z","end":"2026-09-16T22:22:44.91169Z","steps":["trace[817283195] 'process raft request' (duration: 144.256274ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:22:44.911767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.784764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T22:22:44.911787Z","caller":"traceutil/trace.go:171","msg":"trace[173984727] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15241; }","duration":"100.871407ms","start":"2026-09-16T22:22:44.810911Z","end":"2026-09-16T22:22:44.911782Z","steps":["trace[173984727] 'agreement among raft nodes before linearized reading' (duration: 100.816686ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:22:45.021642Z","caller":"traceutil/trace.go:171","msg":"trace[585155517] linearizableReadLoop","detail":"{readStateIndex:15767; appliedIndex:15766; }","duration":"109.890491ms","start":"2026-09-16T22:22:44.911733Z","end":"2026-09-16T22:22:45.021623Z","steps":["trace[585155517] 'read index received' (duration: 47.735881ms)","trace[585155517] 'applied index is now lower than readState.Index' (duration: 62.15382ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:22:45.021706Z","caller":"traceutil/trace.go:171","msg":"trace[1764145224] transaction","detail":"{read_only:false; response_revision:15242; number_of_response:1; }","duration":"199.374473ms","start":"2026-09-16T22:22:44.822292Z","end":"2026-09-16T22:22:45.021666Z","steps":["trace[1764145224] 'process raft request' (duration: 137.261724ms)","trace[1764145224] 'compare' (duration: 61.970166ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:22:45.021807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.698516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-16T22:22:45.021831Z","caller":"traceutil/trace.go:171","msg":"trace[473305166] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15242; }","duration":"210.771778ms","start":"2026-09-16T22:22:44.811053Z","end":"2026-09-16T22:22:45.021825Z","steps":["trace[473305166] 'agreement among raft nodes before linearized reading' (duration: 210.665826ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:22:45.021919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.73877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-09-16T22:22:45.021971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.850318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19475"} {"level":"info","ts":"2026-09-16T22:22:45.023801Z","caller":"traceutil/trace.go:171","msg":"trace[633035864] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:15242; }","duration":"101.959291ms","start":"2026-09-16T22:22:44.920059Z","end":"2026-09-16T22:22:45.022018Z","steps":["trace[633035864] 'agreement among raft nodes before linearized reading' (duration: 101.732696ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:22:45.0239Z","caller":"traceutil/trace.go:171","msg":"trace[407209075] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15242; }","duration":"205.845112ms","start":"2026-09-16T22:22:44.816142Z","end":"2026-09-16T22:22:45.021988Z","steps":["trace[407209075] 'agreement among raft nodes before linearized reading' (duration: 205.513105ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:22:45.52186Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12799} {"level":"info","ts":"2026-09-16T22:22:45.611005Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12799,"took":"86.326286ms","hash":2176553876,"current-db-size-bytes":55230464,"current-db-size":"55 MB","current-db-size-in-use-bytes":30097408,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-16T22:22:45.611062Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2176553876,"revision":12799,"compact-revision":10552} {"level":"info","ts":"2026-09-16T22:27:45.531619Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15249} {"level":"info","ts":"2026-09-16T22:27:45.646892Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15249,"took":"111.600781ms","hash":3080859531,"current-db-size-bytes":55230464,"current-db-size":"55 MB","current-db-size-in-use-bytes":32047104,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-16T22:27:45.646944Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3080859531,"revision":15249,"compact-revision":12799} {"level":"info","ts":"2026-09-16T22:28:21.50053Z","caller":"traceutil/trace.go:171","msg":"trace[820640066] linearizableReadLoop","detail":"{readStateIndex:18371; appliedIndex:18370; }","duration":"157.667888ms","start":"2026-09-16T22:28:21.342844Z","end":"2026-09-16T22:28:21.500512Z","steps":["trace[820640066] 'read index received' (duration: 157.511855ms)","trace[820640066] 'applied index is now lower than readState.Index' (duration: 155.333µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:28:21.50072Z","caller":"traceutil/trace.go:171","msg":"trace[1769485256] transaction","detail":"{read_only:false; response_revision:17763; number_of_response:1; }","duration":"158.119428ms","start":"2026-09-16T22:28:21.342588Z","end":"2026-09-16T22:28:21.500707Z","steps":["trace[1769485256] 'process raft request' (duration: 157.820082ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:28:21.500857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.998435ms","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-09-16T22:28:21.500997Z","caller":"traceutil/trace.go:171","msg":"trace[1943351773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17763; }","duration":"158.172389ms","start":"2026-09-16T22:28:21.342796Z","end":"2026-09-16T22:28:21.500969Z","steps":["trace[1943351773] 'agreement among raft nodes before linearized reading' (duration: 157.844252ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:28:27.408002Z","caller":"traceutil/trace.go:171","msg":"trace[91499759] transaction","detail":"{read_only:false; response_revision:17795; number_of_response:1; }","duration":"128.011123ms","start":"2026-09-16T22:28:27.279949Z","end":"2026-09-16T22:28:27.40796Z","steps":["trace[91499759] 'process raft request' (duration: 127.751147ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:28:27.774112Z","caller":"traceutil/trace.go:171","msg":"trace[1655346180] linearizableReadLoop","detail":"{readStateIndex:18405; appliedIndex:18403; }","duration":"450.987333ms","start":"2026-09-16T22:28:27.3231Z","end":"2026-09-16T22:28:27.774087Z","steps":["trace[1655346180] 'read index received' (duration: 84.48533ms)","trace[1655346180] 'applied index is now lower than readState.Index' (duration: 366.500853ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:28:27.774722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.032951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-16T22:28:27.77474Z","caller":"traceutil/trace.go:171","msg":"trace[596916313] transaction","detail":"{read_only:false; response_revision:17796; number_of_response:1; }","duration":"492.900581ms","start":"2026-09-16T22:28:27.281803Z","end":"2026-09-16T22:28:27.774704Z","steps":["trace[596916313] 'process raft request' (duration: 491.876749ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:28:27.774779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.945541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-r4tsb\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-09-16T22:28:27.774806Z","caller":"traceutil/trace.go:171","msg":"trace[683155596] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17796; }","duration":"245.155404ms","start":"2026-09-16T22:28:27.529627Z","end":"2026-09-16T22:28:27.774782Z","steps":["trace[683155596] 'agreement among raft nodes before linearized reading' (duration: 244.966459ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:28:27.774835Z","caller":"traceutil/trace.go:171","msg":"trace[360530878] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-r4tsb; range_end:; response_count:1; response_revision:17796; }","duration":"197.033273ms","start":"2026-09-16T22:28:27.577785Z","end":"2026-09-16T22:28:27.774818Z","steps":["trace[360530878] 'agreement among raft nodes before linearized reading' (duration: 196.848068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:28:27.774724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.612428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-16T22:28:27.774982Z","caller":"traceutil/trace.go:171","msg":"trace[63813311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17796; }","duration":"451.927634ms","start":"2026-09-16T22:28:27.323041Z","end":"2026-09-16T22:28:27.774968Z","steps":["trace[63813311] 'agreement among raft nodes before linearized reading' (duration: 451.493104ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:28:27.774992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.83026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-09-16T22:28:27.775024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:28:27.323016Z","time spent":"451.993886ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-16T22:28:27.775069Z","caller":"traceutil/trace.go:171","msg":"trace[91268987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17796; }","duration":"232.975083ms","start":"2026-09-16T22:28:27.542075Z","end":"2026-09-16T22:28:27.77505Z","steps":["trace[91268987] 'agreement among raft nodes before linearized reading' (duration: 232.710847ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:28:27.775332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:28:27.281778Z","time spent":"493.027815ms","remote":"127.0.0.1:52120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20874,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:28:27.99411Z","caller":"traceutil/trace.go:171","msg":"trace[1536124935] linearizableReadLoop","detail":"{readStateIndex:18406; appliedIndex:18405; }","duration":"207.513826ms","start":"2026-09-16T22:28:27.786567Z","end":"2026-09-16T22:28:27.994081Z","steps":["trace[1536124935] 'read index received' (duration: 174.725703ms)","trace[1536124935] 'applied index is now lower than readState.Index' (duration: 32.783093ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:28:27.994209Z","caller":"traceutil/trace.go:171","msg":"trace[1131523998] transaction","detail":"{read_only:false; response_revision:17797; number_of_response:1; }","duration":"210.597603ms","start":"2026-09-16T22:28:27.783561Z","end":"2026-09-16T22:28:27.994159Z","steps":["trace[1131523998] 'process raft request' (duration: 177.688897ms)","trace[1131523998] 'compare' (duration: 32.673271ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:28:27.995161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.567948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945830"} {"level":"info","ts":"2026-09-16T22:28:27.995252Z","caller":"traceutil/trace.go:171","msg":"trace[1627016089] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:17797; }","duration":"208.705371ms","start":"2026-09-16T22:28:27.78653Z","end":"2026-09-16T22:28:27.995235Z","steps":["trace[1627016089] 'agreement among raft nodes before linearized reading' (duration: 207.72036ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:28:28.013592Z","caller":"traceutil/trace.go:171","msg":"trace[1786788923] transaction","detail":"{read_only:false; response_revision:17798; number_of_response:1; }","duration":"226.946393ms","start":"2026-09-16T22:28:27.786598Z","end":"2026-09-16T22:28:28.013544Z","steps":["trace[1786788923] 'process raft request' (duration: 226.468233ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:28:28.013639Z","caller":"traceutil/trace.go:171","msg":"trace[1382699965] transaction","detail":"{read_only:false; response_revision:17799; number_of_response:1; }","duration":"222.12192ms","start":"2026-09-16T22:28:27.791495Z","end":"2026-09-16T22:28:28.013617Z","steps":["trace[1382699965] 'process raft request' (duration: 221.731811ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:31:28.382312Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d9b565cb482924cf","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-16T22:31:28.396381Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-16T22:31:28.39693Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-16T22:32:45.539681Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17475} {"level":"info","ts":"2026-09-16T22:32:45.64274Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17475,"took":"100.821931ms","hash":1052497797,"current-db-size-bytes":55230464,"current-db-size":"55 MB","current-db-size-in-use-bytes":34103296,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-16T22:32:45.642818Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1052497797,"revision":17475,"compact-revision":15249} {"level":"info","ts":"2026-09-16T22:33:43.239233Z","caller":"traceutil/trace.go:171","msg":"trace[1667549784] linearizableReadLoop","detail":"{readStateIndex:21012; appliedIndex:21011; }","duration":"104.764485ms","start":"2026-09-16T22:33:43.134439Z","end":"2026-09-16T22:33:43.239204Z","steps":["trace[1667549784] 'read index received' (duration: 104.566101ms)","trace[1667549784] 'applied index is now lower than readState.Index' (duration: 196.974µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:33:43.239359Z","caller":"traceutil/trace.go:171","msg":"trace[800364193] transaction","detail":"{read_only:false; response_revision:20320; number_of_response:1; }","duration":"162.352059ms","start":"2026-09-16T22:33:43.076966Z","end":"2026-09-16T22:33:43.239318Z","steps":["trace[800364193] 'process raft request' (duration: 162.051762ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:33:43.239391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.946618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:33:43.239589Z","caller":"traceutil/trace.go:171","msg":"trace[1476183500] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:20320; }","duration":"105.183323ms","start":"2026-09-16T22:33:43.134369Z","end":"2026-09-16T22:33:43.239552Z","steps":["trace[1476183500] 'agreement among raft nodes before linearized reading' (duration: 104.959209ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:33:53.90051Z","caller":"traceutil/trace.go:171","msg":"trace[1253991212] transaction","detail":"{read_only:false; response_revision:20371; number_of_response:1; }","duration":"116.932105ms","start":"2026-09-16T22:33:53.78354Z","end":"2026-09-16T22:33:53.900472Z","steps":["trace[1253991212] 'process raft request' (duration: 116.68772ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:33:53.901791Z","caller":"traceutil/trace.go:171","msg":"trace[455737860] transaction","detail":"{read_only:false; response_revision:20372; number_of_response:1; }","duration":"111.617202ms","start":"2026-09-16T22:33:53.790139Z","end":"2026-09-16T22:33:53.901757Z","steps":["trace[455737860] 'process raft request' (duration: 111.391416ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:33:59.425833Z","caller":"traceutil/trace.go:171","msg":"trace[418070087] transaction","detail":"{read_only:false; response_revision:20402; number_of_response:1; }","duration":"105.49781ms","start":"2026-09-16T22:33:59.320305Z","end":"2026-09-16T22:33:59.425803Z","steps":["trace[418070087] 'process raft request' (duration: 105.341306ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:34:00.118785Z","caller":"traceutil/trace.go:171","msg":"trace[1632781990] transaction","detail":"{read_only:false; response_revision:20404; number_of_response:1; }","duration":"199.471504ms","start":"2026-09-16T22:33:59.919289Z","end":"2026-09-16T22:34:00.11876Z","steps":["trace[1632781990] 'process raft request' (duration: 199.28012ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:34:00.337299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.170408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-16T22:34:00.337422Z","caller":"traceutil/trace.go:171","msg":"trace[1297973450] linearizableReadLoop","detail":"{readStateIndex:21101; appliedIndex:21099; }","duration":"404.682662ms","start":"2026-09-16T22:33:59.932723Z","end":"2026-09-16T22:34:00.337405Z","steps":["trace[1297973450] 'read index received' (duration: 185.965075ms)","trace[1297973450] 'applied index is now lower than readState.Index' (duration: 218.715877ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:34:00.337606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.881976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-16T22:34:00.33764Z","caller":"traceutil/trace.go:171","msg":"trace[201816992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20405; }","duration":"404.951728ms","start":"2026-09-16T22:33:59.932678Z","end":"2026-09-16T22:34:00.33763Z","steps":["trace[201816992] 'agreement among raft nodes before linearized reading' (duration: 404.802995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:34:00.337669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:33:59.932657Z","time spent":"405.005109ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-16T22:34:00.337671Z","caller":"traceutil/trace.go:171","msg":"trace[707879107] transaction","detail":"{read_only:false; response_revision:20405; number_of_response:1; }","duration":"414.512673ms","start":"2026-09-16T22:33:59.922918Z","end":"2026-09-16T22:34:00.33743Z","steps":["trace[707879107] 'process raft request' (duration: 284.954396ms)","trace[707879107] 'compare' (duration: 129.042976ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:34:00.337764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.032065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-09-16T22:34:00.337767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.203491ms","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-09-16T22:34:00.337838Z","caller":"traceutil/trace.go:171","msg":"trace[1546036081] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20405; }","duration":"404.306023ms","start":"2026-09-16T22:33:59.933515Z","end":"2026-09-16T22:34:00.337821Z","steps":["trace[1546036081] 'agreement among raft nodes before linearized reading' (duration: 404.052337ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:34:00.337835Z","caller":"traceutil/trace.go:171","msg":"trace[981445788] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20405; }","duration":"311.155148ms","start":"2026-09-16T22:34:00.026659Z","end":"2026-09-16T22:34:00.337814Z","steps":["trace[981445788] 'agreement among raft nodes before linearized reading' (duration: 310.942623ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:34:00.337846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:33:59.922891Z","time spent":"414.861319ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T22:34:00.337874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:34:00.026635Z","time spent":"311.230579ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-16T22:34:00.337874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:33:59.933502Z","time spent":"404.363955ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-16T22:34:00.550682Z","caller":"traceutil/trace.go:171","msg":"trace[1664490745] transaction","detail":"{read_only:false; response_revision:20406; number_of_response:1; }","duration":"208.570158ms","start":"2026-09-16T22:34:00.342087Z","end":"2026-09-16T22:34:00.550658Z","steps":["trace[1664490745] 'process raft request' (duration: 157.067245ms)","trace[1664490745] 'compare' (duration: 51.35099ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:34:00.555778Z","caller":"traceutil/trace.go:171","msg":"trace[82011853] transaction","detail":"{read_only:false; response_revision:20407; number_of_response:1; }","duration":"213.541036ms","start":"2026-09-16T22:34:00.342213Z","end":"2026-09-16T22:34:00.555754Z","steps":["trace[82011853] 'process raft request' (duration: 213.328921ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:34:00.556101Z","caller":"traceutil/trace.go:171","msg":"trace[589929512] transaction","detail":"{read_only:false; response_revision:20409; number_of_response:1; }","duration":"158.311233ms","start":"2026-09-16T22:34:00.397766Z","end":"2026-09-16T22:34:00.556078Z","steps":["trace[589929512] 'process raft request' (duration: 158.128319ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:34:00.556086Z","caller":"traceutil/trace.go:171","msg":"trace[1260128911] transaction","detail":"{read_only:false; response_revision:20408; number_of_response:1; }","duration":"212.829951ms","start":"2026-09-16T22:34:00.34322Z","end":"2026-09-16T22:34:00.55605Z","steps":["trace[1260128911] 'process raft request' (duration: 212.491344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:35:04.502676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.236438ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-16T22:35:04.502936Z","caller":"traceutil/trace.go:171","msg":"trace[649535766] transaction","detail":"{read_only:false; response_revision:21017; number_of_response:1; }","duration":"132.121201ms","start":"2026-09-16T22:35:04.370798Z","end":"2026-09-16T22:35:04.502919Z","steps":["trace[649535766] 'process raft request' (duration: 131.982828ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:36:35.053311Z","caller":"traceutil/trace.go:171","msg":"trace[55225986] transaction","detail":"{read_only:false; response_revision:21638; number_of_response:1; }","duration":"111.779266ms","start":"2026-09-16T22:36:34.941508Z","end":"2026-09-16T22:36:35.053288Z","steps":["trace[55225986] 'process raft request' (duration: 111.725815ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:36:35.053358Z","caller":"traceutil/trace.go:171","msg":"trace[1506799985] transaction","detail":"{read_only:false; response_revision:21637; number_of_response:1; }","duration":"115.090348ms","start":"2026-09-16T22:36:34.938233Z","end":"2026-09-16T22:36:35.053323Z","steps":["trace[1506799985] 'process raft request' (duration: 114.836251ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:37:45.547219Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20005} {"level":"info","ts":"2026-09-16T22:37:45.66911Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20005,"took":"118.266084ms","hash":1653107201,"current-db-size-bytes":55230464,"current-db-size":"55 MB","current-db-size-in-use-bytes":31940608,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-16T22:37:45.669175Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1653107201,"revision":20005,"compact-revision":17475} {"level":"info","ts":"2026-09-16T22:37:56.271138Z","caller":"traceutil/trace.go:171","msg":"trace[125208491] linearizableReadLoop","detail":"{readStateIndex:23618; appliedIndex:23617; }","duration":"129.205418ms","start":"2026-09-16T22:37:56.141902Z","end":"2026-09-16T22:37:56.271108Z","steps":["trace[125208491] 'read index received' (duration: 127.276596ms)","trace[125208491] 'applied index is now lower than readState.Index' (duration: 1.927702ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:37:56.271226Z","caller":"traceutil/trace.go:171","msg":"trace[1353544187] transaction","detail":"{read_only:false; response_revision:22849; number_of_response:1; }","duration":"192.835342ms","start":"2026-09-16T22:37:56.078362Z","end":"2026-09-16T22:37:56.271198Z","steps":["trace[1353544187] 'process raft request' (duration: 190.92328ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:37:56.271458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.536896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-16T22:37:56.271542Z","caller":"traceutil/trace.go:171","msg":"trace[1957769055] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:22849; }","duration":"129.621107ms","start":"2026-09-16T22:37:56.141886Z","end":"2026-09-16T22:37:56.271507Z","steps":["trace[1957769055] 'agreement among raft nodes before linearized reading' (duration: 129.339581ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:24.79739Z","caller":"traceutil/trace.go:171","msg":"trace[381433746] transaction","detail":"{read_only:false; response_revision:24971; number_of_response:1; }","duration":"103.348255ms","start":"2026-09-16T22:41:24.694011Z","end":"2026-09-16T22:41:24.797359Z","steps":["trace[381433746] 'process raft request' (duration: 103.153681ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:25.217378Z","caller":"traceutil/trace.go:171","msg":"trace[221922900] linearizableReadLoop","detail":"{readStateIndex:25793; appliedIndex:25792; }","duration":"111.05232ms","start":"2026-09-16T22:41:25.106301Z","end":"2026-09-16T22:41:25.217354Z","steps":["trace[221922900] 'read index received' (duration: 110.869076ms)","trace[221922900] 'applied index is now lower than readState.Index' (duration: 182.164µs)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:41:25.217592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.268104ms","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-09-16T22:41:25.217643Z","caller":"traceutil/trace.go:171","msg":"trace[517100313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24972; }","duration":"111.378577ms","start":"2026-09-16T22:41:25.106248Z","end":"2026-09-16T22:41:25.217627Z","steps":["trace[517100313] 'agreement among raft nodes before linearized reading' (duration: 111.201663ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:25.2178Z","caller":"traceutil/trace.go:171","msg":"trace[455308786] transaction","detail":"{read_only:false; response_revision:24972; number_of_response:1; }","duration":"141.715277ms","start":"2026-09-16T22:41:25.076068Z","end":"2026-09-16T22:41:25.217783Z","steps":["trace[455308786] 'process raft request' (duration: 141.136205ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:25.487485Z","caller":"traceutil/trace.go:171","msg":"trace[1399992042] transaction","detail":"{read_only:false; response_revision:24973; number_of_response:1; }","duration":"265.210582ms","start":"2026-09-16T22:41:25.222238Z","end":"2026-09-16T22:41:25.487449Z","steps":["trace[1399992042] 'process raft request' (duration: 250.669961ms)","trace[1399992042] 'compare' (duration: 14.339807ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:41:25.90731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.232762ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-16T22:41:25.907434Z","caller":"traceutil/trace.go:171","msg":"trace[1574027610] linearizableReadLoop","detail":"{readStateIndex:25795; appliedIndex:25794; }","duration":"400.756667ms","start":"2026-09-16T22:41:25.506657Z","end":"2026-09-16T22:41:25.907414Z","steps":["trace[1574027610] 'read index received' (duration: 276.333652ms)","trace[1574027610] 'applied index is now lower than readState.Index' (duration: 124.421165ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:25.907524Z","caller":"traceutil/trace.go:171","msg":"trace[213205723] transaction","detail":"{read_only:false; response_revision:24974; number_of_response:1; }","duration":"614.017457ms","start":"2026-09-16T22:41:25.293484Z","end":"2026-09-16T22:41:25.907502Z","steps":["trace[213205723] 'process raft request' (duration: 489.488339ms)","trace[213205723] 'compare' (duration: 110.304233ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:41:25.907553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.88864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:41:25.907592Z","caller":"traceutil/trace.go:171","msg":"trace[1919708380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24974; }","duration":"400.956232ms","start":"2026-09-16T22:41:25.506621Z","end":"2026-09-16T22:41:25.907578Z","steps":["trace[1919708380] 'agreement among raft nodes before linearized reading' (duration: 400.88091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:25.907626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:25.293438Z","time spent":"614.130209ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T22:41:25.907639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:25.506605Z","time spent":"401.022063ms","remote":"127.0.0.1:51922","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-09-16T22:41:26.173718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.884559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-16T22:41:26.173854Z","caller":"traceutil/trace.go:171","msg":"trace[88845503] transaction","detail":"{read_only:false; response_revision:24976; number_of_response:1; }","duration":"214.70684ms","start":"2026-09-16T22:41:25.959136Z","end":"2026-09-16T22:41:26.173843Z","steps":["trace[88845503] 'process raft request' (duration: 214.655319ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:26.174096Z","caller":"traceutil/trace.go:171","msg":"trace[185830710] transaction","detail":"{read_only:false; response_revision:24975; number_of_response:1; }","duration":"527.930132ms","start":"2026-09-16T22:41:25.646158Z","end":"2026-09-16T22:41:26.174088Z","steps":["trace[185830710] 'process raft request' (duration: 385.619504ms)","trace[185830710] 'compare' (duration: 141.588833ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:41:26.17414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:25.64613Z","time spent":"527.987803ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:41:26.174235Z","caller":"traceutil/trace.go:171","msg":"trace[1140658722] linearizableReadLoop","detail":"{readStateIndex:25796; appliedIndex:25795; }","duration":"266.757146ms","start":"2026-09-16T22:41:25.907472Z","end":"2026-09-16T22:41:26.174229Z","steps":["trace[1140658722] 'read index received' (duration: 124.123291ms)","trace[1140658722] 'applied index is now lower than readState.Index' (duration: 142.633145ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:41:26.174409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.278963ms","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-09-16T22:41:26.174433Z","caller":"traceutil/trace.go:171","msg":"trace[1096347813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24976; }","duration":"341.338794ms","start":"2026-09-16T22:41:25.833088Z","end":"2026-09-16T22:41:26.174426Z","steps":["trace[1096347813] 'agreement among raft nodes before linearized reading' (duration: 341.260243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:26.17445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:25.833068Z","time spent":"341.378605ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-16T22:41:26.174566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.551627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-16T22:41:26.174588Z","caller":"traceutil/trace.go:171","msg":"trace[1864755352] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24976; }","duration":"599.592538ms","start":"2026-09-16T22:41:25.57499Z","end":"2026-09-16T22:41:26.174582Z","steps":["trace[1864755352] 'agreement among raft nodes before linearized reading' (duration: 599.539357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:26.174603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:25.57498Z","time spent":"599.619729ms","remote":"127.0.0.1:52190","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-09-16T22:41:26.174785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.844086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-16T22:41:26.174879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.782638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-16T22:41:26.174964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.395814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-16T22:41:26.175016Z","caller":"traceutil/trace.go:171","msg":"trace[413604950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24976; }","duration":"631.967812ms","start":"2026-09-16T22:41:25.543028Z","end":"2026-09-16T22:41:26.174995Z","steps":["trace[413604950] 'agreement among raft nodes before linearized reading' (duration: 631.609795ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:26.175064Z","caller":"traceutil/trace.go:171","msg":"trace[2117225033] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24976; }","duration":"606.563058ms","start":"2026-09-16T22:41:25.568476Z","end":"2026-09-16T22:41:26.175039Z","steps":["trace[2117225033] 'agreement among raft nodes before linearized reading' (duration: 606.224171ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:26.17508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:25.543013Z","time spent":"632.059304ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-16T22:41:26.175113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:25.568413Z","time spent":"606.6459ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-16T22:41:26.17503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.933837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-09-16T22:41:26.175247Z","caller":"traceutil/trace.go:171","msg":"trace[1021091439] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:24976; }","duration":"209.177912ms","start":"2026-09-16T22:41:25.966056Z","end":"2026-09-16T22:41:26.175234Z","steps":["trace[1021091439] 'agreement among raft nodes before linearized reading' (duration: 208.884456ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:26.17547Z","caller":"traceutil/trace.go:171","msg":"trace[1563434079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24976; }","duration":"265.994009ms","start":"2026-09-16T22:41:25.908864Z","end":"2026-09-16T22:41:26.174858Z","steps":["trace[1563434079] 'agreement among raft nodes before linearized reading' (duration: 265.845066ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:26.51425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.878908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-16T22:41:26.514328Z","caller":"traceutil/trace.go:171","msg":"trace[391596836] transaction","detail":"{read_only:false; response_revision:24977; number_of_response:1; }","duration":"333.136278ms","start":"2026-09-16T22:41:26.181179Z","end":"2026-09-16T22:41:26.514315Z","steps":["trace[391596836] 'process raft request' (duration: 164.120427ms)","trace[391596836] 'compare' (duration: 168.709425ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:41:26.514364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:26.181166Z","time spent":"333.181499ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:41:26.525908Z","caller":"traceutil/trace.go:171","msg":"trace[1008274043] linearizableReadLoop","detail":"{readStateIndex:25799; appliedIndex:25797; }","duration":"343.830778ms","start":"2026-09-16T22:41:26.182059Z","end":"2026-09-16T22:41:26.52589Z","steps":["trace[1008274043] 'read index received' (duration: 163.317961ms)","trace[1008274043] 'applied index is now lower than readState.Index' (duration: 180.512167ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:41:26.52614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.064683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b3cd1801-dee1-403c-8a27-1fa6e822cea2\" ","response":"range_response_count:1 size:1316"} {"level":"info","ts":"2026-09-16T22:41:26.526196Z","caller":"traceutil/trace.go:171","msg":"trace[2002185455] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b3cd1801-dee1-403c-8a27-1fa6e822cea2; range_end:; response_count:1; response_revision:24982; }","duration":"344.153334ms","start":"2026-09-16T22:41:26.182022Z","end":"2026-09-16T22:41:26.526176Z","steps":["trace[2002185455] 'agreement among raft nodes before linearized reading' (duration: 343.984601ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:26.52626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:26.182012Z","time spent":"344.240637ms","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1339,"request content":"key:\"/registry/persistentvolumes/pvc-b3cd1801-dee1-403c-8a27-1fa6e822cea2\" "} {"level":"info","ts":"2026-09-16T22:41:26.526374Z","caller":"traceutil/trace.go:171","msg":"trace[1663140561] transaction","detail":"{read_only:false; response_revision:24981; number_of_response:1; }","duration":"307.690753ms","start":"2026-09-16T22:41:26.218636Z","end":"2026-09-16T22:41:26.526326Z","steps":["trace[1663140561] 'process raft request' (duration: 307.212153ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:26.526607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:26.218605Z","time spent":"307.889108ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:41:26.526449Z","caller":"traceutil/trace.go:171","msg":"trace[1771006125] transaction","detail":"{read_only:false; response_revision:24982; number_of_response:1; }","duration":"258.775585ms","start":"2026-09-16T22:41:26.267667Z","end":"2026-09-16T22:41:26.526442Z","steps":["trace[1771006125] 'process raft request' (duration: 258.201263ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:26.526627Z","caller":"traceutil/trace.go:171","msg":"trace[1924028019] transaction","detail":"{read_only:false; response_revision:24980; number_of_response:1; }","duration":"343.225783ms","start":"2026-09-16T22:41:26.183394Z","end":"2026-09-16T22:41:26.526619Z","steps":["trace[1924028019] 'process raft request' (duration: 342.434607ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:26.527255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:26.183381Z","time spent":"343.844047ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:41:26.526094Z","caller":"traceutil/trace.go:171","msg":"trace[890995047] transaction","detail":"{read_only:false; response_revision:24979; number_of_response:1; }","duration":"343.774287ms","start":"2026-09-16T22:41:26.182242Z","end":"2026-09-16T22:41:26.526016Z","steps":["trace[890995047] 'process raft request' (duration: 343.559262ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:26.527446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:26.182211Z","time spent":"345.207817ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:41:26.526452Z","caller":"traceutil/trace.go:171","msg":"trace[96061521] transaction","detail":"{read_only:false; response_revision:24978; number_of_response:1; }","duration":"345.125335ms","start":"2026-09-16T22:41:26.181319Z","end":"2026-09-16T22:41:26.526444Z","steps":["trace[96061521] 'process raft request' (duration: 344.353298ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:26.528018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:26.181282Z","time spent":"346.705479ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:41:26.770439Z","caller":"traceutil/trace.go:171","msg":"trace[1666478289] linearizableReadLoop","detail":"{readStateIndex:25804; appliedIndex:25803; }","duration":"152.337163ms","start":"2026-09-16T22:41:26.618082Z","end":"2026-09-16T22:41:26.77042Z","steps":["trace[1666478289] 'read index received' (duration: 54.982928ms)","trace[1666478289] 'applied index is now lower than readState.Index' (duration: 97.353095ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:26.770609Z","caller":"traceutil/trace.go:171","msg":"trace[1149479602] transaction","detail":"{read_only:false; response_revision:24983; number_of_response:1; }","duration":"349.923048ms","start":"2026-09-16T22:41:26.420678Z","end":"2026-09-16T22:41:26.770601Z","steps":["trace[1149479602] 'process raft request' (duration: 252.41033ms)","trace[1149479602] 'compare' (duration: 96.766742ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:41:26.770681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:26.420655Z","time spent":"349.969389ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T22:41:26.77084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.759042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-09-16T22:41:26.770862Z","caller":"traceutil/trace.go:171","msg":"trace[1412986320] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24983; }","duration":"152.810014ms","start":"2026-09-16T22:41:26.618045Z","end":"2026-09-16T22:41:26.770855Z","steps":["trace[1412986320] 'agreement among raft nodes before linearized reading' (duration: 152.734132ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:26.876194Z","caller":"traceutil/trace.go:171","msg":"trace[227132323] linearizableReadLoop","detail":"{readStateIndex:25805; appliedIndex:25804; }","duration":"105.689365ms","start":"2026-09-16T22:41:26.770479Z","end":"2026-09-16T22:41:26.876168Z","steps":["trace[227132323] 'read index received' (duration: 105.650184ms)","trace[227132323] 'applied index is now lower than readState.Index' (duration: 38.581µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:26.87628Z","caller":"traceutil/trace.go:171","msg":"trace[911170363] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"157.488934ms","start":"2026-09-16T22:41:26.718782Z","end":"2026-09-16T22:41:26.876271Z","steps":["trace[911170363] 'process raft request' (duration: 157.269589ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:26.876405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.861727ms","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-09-16T22:41:26.876456Z","caller":"traceutil/trace.go:171","msg":"trace[425549050] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24984; }","duration":"209.98319ms","start":"2026-09-16T22:41:26.666459Z","end":"2026-09-16T22:41:26.876442Z","steps":["trace[425549050] 'agreement among raft nodes before linearized reading' (duration: 209.858637ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:26.877099Z","caller":"traceutil/trace.go:171","msg":"trace[1085025198] transaction","detail":"{read_only:false; response_revision:24985; number_of_response:1; }","duration":"100.783239ms","start":"2026-09-16T22:41:26.776306Z","end":"2026-09-16T22:41:26.877089Z","steps":["trace[1085025198] 'process raft request' (duration: 100.486163ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:32.147144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.524921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-16T22:41:32.147257Z","caller":"traceutil/trace.go:171","msg":"trace[2028488981] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25019; }","duration":"104.686684ms","start":"2026-09-16T22:41:32.042545Z","end":"2026-09-16T22:41:32.147232Z","steps":["trace[2028488981] 'range keys from in-memory index tree' (duration: 104.312906ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:32.325249Z","caller":"traceutil/trace.go:171","msg":"trace[133151575] transaction","detail":"{read_only:false; response_revision:25020; number_of_response:1; }","duration":"132.178652ms","start":"2026-09-16T22:41:32.193051Z","end":"2026-09-16T22:41:32.32523Z","steps":["trace[133151575] 'process raft request' (duration: 132.017559ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:32.464101Z","caller":"traceutil/trace.go:171","msg":"trace[505172746] linearizableReadLoop","detail":"{readStateIndex:25843; appliedIndex:25841; }","duration":"225.099215ms","start":"2026-09-16T22:41:32.238977Z","end":"2026-09-16T22:41:32.464076Z","steps":["trace[505172746] 'read index received' (duration: 86.115866ms)","trace[505172746] 'applied index is now lower than readState.Index' (duration: 138.981829ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:32.464463Z","caller":"traceutil/trace.go:171","msg":"trace[614292970] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"270.084498ms","start":"2026-09-16T22:41:32.194363Z","end":"2026-09-16T22:41:32.464447Z","steps":["trace[614292970] 'process raft request' (duration: 258.912638ms)","trace[614292970] 'compare' (duration: 10.688259ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:41:32.464733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.742408ms","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-09-16T22:41:32.464774Z","caller":"traceutil/trace.go:171","msg":"trace[980785575] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25021; }","duration":"225.86385ms","start":"2026-09-16T22:41:32.238899Z","end":"2026-09-16T22:41:32.464763Z","steps":["trace[980785575] 'agreement among raft nodes before linearized reading' (duration: 225.784859ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:32.465049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.654347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:41:32.46509Z","caller":"traceutil/trace.go:171","msg":"trace[452242825] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:25021; }","duration":"121.730538ms","start":"2026-09-16T22:41:32.343349Z","end":"2026-09-16T22:41:32.465079Z","steps":["trace[452242825] 'agreement among raft nodes before linearized reading' (duration: 121.656896ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:32.881614Z","caller":"traceutil/trace.go:171","msg":"trace[1587663325] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"335.421908ms","start":"2026-09-16T22:41:32.546166Z","end":"2026-09-16T22:41:32.881587Z","steps":["trace[1587663325] 'process raft request' (duration: 335.245034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:32.881843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:32.546147Z","time spent":"335.53907ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:41:32.89249Z","caller":"traceutil/trace.go:171","msg":"trace[1659255788] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"162.706156ms","start":"2026-09-16T22:41:32.729756Z","end":"2026-09-16T22:41:32.892462Z","steps":["trace[1659255788] 'process raft request' (duration: 162.635025ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:32.892546Z","caller":"traceutil/trace.go:171","msg":"trace[1958378990] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"342.839866ms","start":"2026-09-16T22:41:32.549684Z","end":"2026-09-16T22:41:32.892524Z","steps":["trace[1958378990] 'process raft request' (duration: 342.55348ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:32.89268Z","caller":"traceutil/trace.go:171","msg":"trace[817771240] linearizableReadLoop","detail":"{readStateIndex:25846; appliedIndex:25843; }","duration":"340.21052ms","start":"2026-09-16T22:41:32.552457Z","end":"2026-09-16T22:41:32.892668Z","steps":["trace[817771240] 'read index received' (duration: 329.068782ms)","trace[817771240] 'applied index is now lower than readState.Index' (duration: 11.140578ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:41:32.892675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:32.549675Z","time spent":"342.913909ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:41:32.89265Z","caller":"traceutil/trace.go:171","msg":"trace[483148139] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"341.174031ms","start":"2026-09-16T22:41:32.551429Z","end":"2026-09-16T22:41:32.892603Z","steps":["trace[483148139] 'process raft request' (duration: 340.925416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:32.892826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.374295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-09-16T22:41:32.892826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:32.551402Z","time spent":"341.336924ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T22:41:32.892855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.706904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-16T22:41:32.892917Z","caller":"traceutil/trace.go:171","msg":"trace[301190291] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25026; }","duration":"335.761405ms","start":"2026-09-16T22:41:32.557115Z","end":"2026-09-16T22:41:32.892876Z","steps":["trace[301190291] 'agreement among raft nodes before linearized reading' (duration: 335.706294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:32.892998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:32.557104Z","time spent":"335.884607ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-16T22:41:32.893121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.637681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:41:32.893224Z","caller":"traceutil/trace.go:171","msg":"trace[235135812] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25026; }","duration":"299.809475ms","start":"2026-09-16T22:41:32.593385Z","end":"2026-09-16T22:41:32.893194Z","steps":["trace[235135812] 'agreement among raft nodes before linearized reading' (duration: 299.628021ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:32.893285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.060037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-16T22:41:32.893367Z","caller":"traceutil/trace.go:171","msg":"trace[630309606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25026; }","duration":"333.150718ms","start":"2026-09-16T22:41:32.56018Z","end":"2026-09-16T22:41:32.89333Z","steps":["trace[630309606] 'agreement among raft nodes before linearized reading' (duration: 332.974175ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:32.892869Z","caller":"traceutil/trace.go:171","msg":"trace[921733239] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25026; }","duration":"340.443195ms","start":"2026-09-16T22:41:32.552413Z","end":"2026-09-16T22:41:32.892856Z","steps":["trace[921733239] 'agreement among raft nodes before linearized reading' (duration: 340.368304ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:32.893499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:32.5524Z","time spent":"341.081129ms","remote":"127.0.0.1:52190","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-09-16T22:41:32.893408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:32.560166Z","time spent":"333.230211ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-16T22:41:33.249413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.865996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-16T22:41:33.249509Z","caller":"traceutil/trace.go:171","msg":"trace[797697805] transaction","detail":"{read_only:false; response_revision:25031; number_of_response:1; }","duration":"164.680209ms","start":"2026-09-16T22:41:33.08481Z","end":"2026-09-16T22:41:33.249491Z","steps":["trace[797697805] 'process raft request' (duration: 60.65644ms)","trace[797697805] 'compare' (duration: 103.438157ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:33.795766Z","caller":"traceutil/trace.go:171","msg":"trace[1673683109] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"266.214144ms","start":"2026-09-16T22:41:33.52953Z","end":"2026-09-16T22:41:33.795744Z","steps":["trace[1673683109] 'process raft request' (duration: 266.061411ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:34.094822Z","caller":"traceutil/trace.go:171","msg":"trace[1857266852] transaction","detail":"{read_only:false; response_revision:25034; number_of_response:1; }","duration":"148.164815ms","start":"2026-09-16T22:41:33.946618Z","end":"2026-09-16T22:41:34.094783Z","steps":["trace[1857266852] 'process raft request' (duration: 60.799783ms)","trace[1857266852] 'compare' (duration: 86.938363ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:34.485384Z","caller":"traceutil/trace.go:171","msg":"trace[778829071] transaction","detail":"{read_only:false; response_revision:25035; number_of_response:1; }","duration":"153.99449ms","start":"2026-09-16T22:41:34.331365Z","end":"2026-09-16T22:41:34.485359Z","steps":["trace[778829071] 'process raft request' (duration: 153.816956ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:34.713426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.512846ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-16T22:41:34.713626Z","caller":"traceutil/trace.go:171","msg":"trace[1945078203] linearizableReadLoop","detail":"{readStateIndex:25858; appliedIndex:25856; }","duration":"300.96733ms","start":"2026-09-16T22:41:34.412643Z","end":"2026-09-16T22:41:34.71361Z","steps":["trace[1945078203] 'read index received' (duration: 72.620776ms)","trace[1945078203] 'applied index is now lower than readState.Index' (duration: 228.345334ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:34.71368Z","caller":"traceutil/trace.go:171","msg":"trace[277366683] transaction","detail":"{read_only:false; response_revision:25036; number_of_response:1; }","duration":"237.169833ms","start":"2026-09-16T22:41:34.476489Z","end":"2026-09-16T22:41:34.713659Z","steps":["trace[277366683] 'process raft request' (duration: 237.013709ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:34.713734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.444687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:41:34.713765Z","caller":"traceutil/trace.go:171","msg":"trace[460571813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25036; }","duration":"278.516099ms","start":"2026-09-16T22:41:34.435239Z","end":"2026-09-16T22:41:34.713756Z","steps":["trace[460571813] 'agreement among raft nodes before linearized reading' (duration: 278.452227ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:34.713773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.094762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-16T22:41:34.713866Z","caller":"traceutil/trace.go:171","msg":"trace[1942207798] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:25036; }","duration":"301.245065ms","start":"2026-09-16T22:41:34.4126Z","end":"2026-09-16T22:41:34.713845Z","steps":["trace[1942207798] 'agreement among raft nodes before linearized reading' (duration: 301.070292ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:34.713909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:34.412578Z","time spent":"301.319987ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":31,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-09-16T22:41:35.217468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.527144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-16T22:41:35.217735Z","caller":"traceutil/trace.go:171","msg":"trace[968731540] linearizableReadLoop","detail":"{readStateIndex:25864; appliedIndex:25860; }","duration":"230.219863ms","start":"2026-09-16T22:41:34.987498Z","end":"2026-09-16T22:41:35.217718Z","steps":["trace[968731540] 'read index received' (duration: 129.331791ms)","trace[968731540] 'applied index is now lower than readState.Index' (duration: 100.887002ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:35.217787Z","caller":"traceutil/trace.go:171","msg":"trace[1208618050] transaction","detail":"{read_only:false; response_revision:25040; number_of_response:1; }","duration":"281.051072ms","start":"2026-09-16T22:41:34.936722Z","end":"2026-09-16T22:41:35.217773Z","steps":["trace[1208618050] 'process raft request' (duration: 280.904819ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:35.217853Z","caller":"traceutil/trace.go:171","msg":"trace[808778089] transaction","detail":"{read_only:false; response_revision:25038; number_of_response:1; }","duration":"316.623065ms","start":"2026-09-16T22:41:34.901204Z","end":"2026-09-16T22:41:35.217827Z","steps":["trace[808778089] 'process raft request' (duration: 215.644901ms)","trace[808778089] 'compare' (duration: 100.367421ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:35.217776Z","caller":"traceutil/trace.go:171","msg":"trace[1075759744] transaction","detail":"{read_only:false; response_revision:25039; number_of_response:1; }","duration":"315.799407ms","start":"2026-09-16T22:41:34.901948Z","end":"2026-09-16T22:41:35.217748Z","steps":["trace[1075759744] 'process raft request' (duration: 315.620453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:35.217993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:34.901166Z","time spent":"316.717787ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T22:41:35.218003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:34.901871Z","time spent":"316.098564ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:41:35.217854Z","caller":"traceutil/trace.go:171","msg":"trace[735884680] transaction","detail":"{read_only:false; response_revision:25041; number_of_response:1; }","duration":"239.84726ms","start":"2026-09-16T22:41:34.977996Z","end":"2026-09-16T22:41:35.217844Z","steps":["trace[735884680] 'process raft request' (duration: 239.677156ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:35.218067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.56565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-16T22:41:35.218102Z","caller":"traceutil/trace.go:171","msg":"trace[1995816990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25041; }","duration":"230.638931ms","start":"2026-09-16T22:41:34.987455Z","end":"2026-09-16T22:41:35.218094Z","steps":["trace[1995816990] 'agreement among raft nodes before linearized reading' (duration: 230.418547ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:35.218167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.295355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-16T22:41:35.218218Z","caller":"traceutil/trace.go:171","msg":"trace[523375035] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25041; }","duration":"230.358786ms","start":"2026-09-16T22:41:34.987845Z","end":"2026-09-16T22:41:35.218204Z","steps":["trace[523375035] 'agreement among raft nodes before linearized reading' (duration: 230.222903ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:35.21842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.706763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-16T22:41:35.218517Z","caller":"traceutil/trace.go:171","msg":"trace[405613603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25041; }","duration":"230.847126ms","start":"2026-09-16T22:41:34.987649Z","end":"2026-09-16T22:41:35.218496Z","steps":["trace[405613603] 'agreement among raft nodes before linearized reading' (duration: 230.379176ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:35.345294Z","caller":"traceutil/trace.go:171","msg":"trace[1502317898] transaction","detail":"{read_only:false; response_revision:25042; number_of_response:1; }","duration":"122.353043ms","start":"2026-09-16T22:41:35.222411Z","end":"2026-09-16T22:41:35.344764Z","steps":["trace[1502317898] 'process raft request' (duration: 109.142839ms)","trace[1502317898] 'compare' (duration: 13.05767ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:35.444619Z","caller":"traceutil/trace.go:171","msg":"trace[1732931508] transaction","detail":"{read_only:false; response_revision:25043; number_of_response:1; }","duration":"221.275292ms","start":"2026-09-16T22:41:35.223314Z","end":"2026-09-16T22:41:35.444589Z","steps":["trace[1732931508] 'process raft request' (duration: 221.054377ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:35.444785Z","caller":"traceutil/trace.go:171","msg":"trace[164645818] linearizableReadLoop","detail":"{readStateIndex:25867; appliedIndex:25864; }","duration":"139.388197ms","start":"2026-09-16T22:41:35.305364Z","end":"2026-09-16T22:41:35.444752Z","steps":["trace[164645818] 'read index received' (duration: 26.168511ms)","trace[164645818] 'applied index is now lower than readState.Index' (duration: 113.217276ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:35.444815Z","caller":"traceutil/trace.go:171","msg":"trace[1493594224] transaction","detail":"{read_only:false; response_revision:25044; number_of_response:1; }","duration":"220.807422ms","start":"2026-09-16T22:41:35.223973Z","end":"2026-09-16T22:41:35.44478Z","steps":["trace[1493594224] 'process raft request' (duration: 220.548157ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:35.582331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.935685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-16T22:41:35.582426Z","caller":"traceutil/trace.go:171","msg":"trace[1857605480] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25044; }","duration":"277.081618ms","start":"2026-09-16T22:41:35.305319Z","end":"2026-09-16T22:41:35.582401Z","steps":["trace[1857605480] 'agreement among raft nodes before linearized reading' (duration: 139.544081ms)","trace[1857605480] 'range keys from in-memory index tree' (duration: 137.251911ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:35.691231Z","caller":"traceutil/trace.go:171","msg":"trace[506674689] transaction","detail":"{read_only:false; response_revision:25045; number_of_response:1; }","duration":"103.539908ms","start":"2026-09-16T22:41:35.587666Z","end":"2026-09-16T22:41:35.691206Z","steps":["trace[506674689] 'process raft request' (duration: 103.363045ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:35.995537Z","caller":"traceutil/trace.go:171","msg":"trace[946236780] transaction","detail":"{read_only:false; response_revision:25046; number_of_response:1; }","duration":"126.781676ms","start":"2026-09-16T22:41:35.868721Z","end":"2026-09-16T22:41:35.995502Z","steps":["trace[946236780] 'process raft request' (duration: 126.627223ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:36.91524Z","caller":"traceutil/trace.go:171","msg":"trace[1950804435] linearizableReadLoop","detail":"{readStateIndex:25872; appliedIndex:25871; }","duration":"157.010364ms","start":"2026-09-16T22:41:36.758208Z","end":"2026-09-16T22:41:36.915218Z","steps":["trace[1950804435] 'read index received' (duration: 156.847751ms)","trace[1950804435] 'applied index is now lower than readState.Index' (duration: 161.583µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:36.915364Z","caller":"traceutil/trace.go:171","msg":"trace[798565951] transaction","detail":"{read_only:false; response_revision:25049; number_of_response:1; }","duration":"195.839326ms","start":"2026-09-16T22:41:36.719514Z","end":"2026-09-16T22:41:36.915353Z","steps":["trace[798565951] 'process raft request' (duration: 195.54522ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:36.915519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.29308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:41:36.915578Z","caller":"traceutil/trace.go:171","msg":"trace[1777024742] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25049; }","duration":"157.401033ms","start":"2026-09-16T22:41:36.758163Z","end":"2026-09-16T22:41:36.915564Z","steps":["trace[1777024742] 'agreement among raft nodes before linearized reading' (duration: 157.304891ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:36.915839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.379747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:41:36.91592Z","caller":"traceutil/trace.go:171","msg":"trace[1335239828] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25049; }","duration":"104.518139ms","start":"2026-09-16T22:41:36.811381Z","end":"2026-09-16T22:41:36.915899Z","steps":["trace[1335239828] 'agreement among raft nodes before linearized reading' (duration: 104.363246ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:37.084275Z","caller":"traceutil/trace.go:171","msg":"trace[2047512872] transaction","detail":"{read_only:false; response_revision:25050; number_of_response:1; }","duration":"105.078552ms","start":"2026-09-16T22:41:36.979145Z","end":"2026-09-16T22:41:37.084224Z","steps":["trace[2047512872] 'process raft request' (duration: 75.660761ms)","trace[2047512872] 'compare' (duration: 29.143565ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:46.409762Z","caller":"traceutil/trace.go:171","msg":"trace[1800065299] linearizableReadLoop","detail":"{readStateIndex:25950; appliedIndex:25949; }","duration":"216.918659ms","start":"2026-09-16T22:41:46.192819Z","end":"2026-09-16T22:41:46.409738Z","steps":["trace[1800065299] 'read index received' (duration: 216.721705ms)","trace[1800065299] 'applied index is now lower than readState.Index' (duration: 195.934µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:46.40982Z","caller":"traceutil/trace.go:171","msg":"trace[134779761] transaction","detail":"{read_only:false; response_revision:25125; number_of_response:1; }","duration":"228.472466ms","start":"2026-09-16T22:41:46.181324Z","end":"2026-09-16T22:41:46.409797Z","steps":["trace[134779761] 'process raft request' (duration: 228.265732ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:46.410107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.257846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-16T22:41:46.410203Z","caller":"traceutil/trace.go:171","msg":"trace[1424081154] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:25125; }","duration":"217.405849ms","start":"2026-09-16T22:41:46.192772Z","end":"2026-09-16T22:41:46.410178Z","steps":["trace[1424081154] 'agreement among raft nodes before linearized reading' (duration: 217.066872ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:46.632435Z","caller":"traceutil/trace.go:171","msg":"trace[2131661626] transaction","detail":"{read_only:false; response_revision:25126; number_of_response:1; }","duration":"210.330317ms","start":"2026-09-16T22:41:46.422068Z","end":"2026-09-16T22:41:46.632398Z","steps":["trace[2131661626] 'process raft request' (duration: 207.715981ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:47.135345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.796184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-16T22:41:47.135505Z","caller":"traceutil/trace.go:171","msg":"trace[1711940855] transaction","detail":"{read_only:false; response_revision:25127; number_of_response:1; }","duration":"512.628125ms","start":"2026-09-16T22:41:46.622842Z","end":"2026-09-16T22:41:47.13547Z","steps":["trace[1711940855] 'process raft request' (duration: 366.611576ms)","trace[1711940855] 'compare' (duration: 145.548419ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:47.135581Z","caller":"traceutil/trace.go:171","msg":"trace[633362769] transaction","detail":"{read_only:false; response_revision:25128; number_of_response:1; }","duration":"186.385015ms","start":"2026-09-16T22:41:46.94917Z","end":"2026-09-16T22:41:47.135555Z","steps":["trace[633362769] 'process raft request' (duration: 186.294463ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:47.135672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:46.622818Z","time spent":"512.758897ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:41:47.135828Z","caller":"traceutil/trace.go:171","msg":"trace[659725684] linearizableReadLoop","detail":"{readStateIndex:25952; appliedIndex:25951; }","duration":"496.423277ms","start":"2026-09-16T22:41:46.639387Z","end":"2026-09-16T22:41:47.13581Z","steps":["trace[659725684] 'read index received' (duration: 350.060021ms)","trace[659725684] 'applied index is now lower than readState.Index' (duration: 146.361566ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:41:47.136054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.667312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1578"} {"level":"info","ts":"2026-09-16T22:41:47.136108Z","caller":"traceutil/trace.go:171","msg":"trace[178038366] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25128; }","duration":"496.768724ms","start":"2026-09-16T22:41:46.639324Z","end":"2026-09-16T22:41:47.136092Z","steps":["trace[178038366] 'agreement among raft nodes before linearized reading' (duration: 496.5669ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:47.136147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:46.639301Z","time spent":"496.834366ms","remote":"127.0.0.1:57016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1601,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-09-16T22:41:47.305498Z","caller":"traceutil/trace.go:171","msg":"trace[1359530293] transaction","detail":"{read_only:false; response_revision:25129; number_of_response:1; }","duration":"187.189751ms","start":"2026-09-16T22:41:47.118282Z","end":"2026-09-16T22:41:47.305472Z","steps":["trace[1359530293] 'process raft request' (duration: 185.417423ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:47.305575Z","caller":"traceutil/trace.go:171","msg":"trace[1284328014] linearizableReadLoop","detail":"{readStateIndex:25954; appliedIndex:25953; }","duration":"169.698587ms","start":"2026-09-16T22:41:47.135861Z","end":"2026-09-16T22:41:47.305559Z","steps":["trace[1284328014] 'read index received' (duration: 167.805536ms)","trace[1284328014] 'applied index is now lower than readState.Index' (duration: 1.890721ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:41:47.305695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.558977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:41:47.305732Z","caller":"traceutil/trace.go:171","msg":"trace[1407334961] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25130; }","duration":"655.656749ms","start":"2026-09-16T22:41:46.650063Z","end":"2026-09-16T22:41:47.30572Z","steps":["trace[1407334961] 'agreement among raft nodes before linearized reading' (duration: 655.572527ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:47.30576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:46.650046Z","time spent":"655.703721ms","remote":"127.0.0.1:38680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"warn","ts":"2026-09-16T22:41:47.305754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.752943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-09-16T22:41:47.30583Z","caller":"traceutil/trace.go:171","msg":"trace[1855384670] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:25129; }","duration":"369.841655ms","start":"2026-09-16T22:41:46.935967Z","end":"2026-09-16T22:41:47.305809Z","steps":["trace[1855384670] 'agreement among raft nodes before linearized reading' (duration: 369.61131ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:47.305868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:46.93593Z","time spent":"369.928357ms","remote":"127.0.0.1:52088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-09-16T22:41:47.305871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.450188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1578"} {"level":"info","ts":"2026-09-16T22:41:47.305992Z","caller":"traceutil/trace.go:171","msg":"trace[1858634254] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25130; }","duration":"636.605171ms","start":"2026-09-16T22:41:46.669359Z","end":"2026-09-16T22:41:47.305965Z","steps":["trace[1858634254] 'agreement among raft nodes before linearized reading' (duration: 636.236244ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:47.306058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:46.669336Z","time spent":"636.706133ms","remote":"127.0.0.1:57016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1601,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-09-16T22:41:47.873005Z","caller":"traceutil/trace.go:171","msg":"trace[1436461554] transaction","detail":"{read_only:false; response_revision:25133; number_of_response:1; }","duration":"562.591966ms","start":"2026-09-16T22:41:47.310379Z","end":"2026-09-16T22:41:47.872971Z","steps":["trace[1436461554] 'process raft request' (duration: 543.429215ms)","trace[1436461554] 'compare' (duration: 18.932306ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:41:47.873209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:47.31036Z","time spent":"562.761389ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:41:48.166331Z","caller":"traceutil/trace.go:171","msg":"trace[1343124426] transaction","detail":"{read_only:false; response_revision:25134; number_of_response:1; }","duration":"851.406394ms","start":"2026-09-16T22:41:47.314892Z","end":"2026-09-16T22:41:48.166299Z","steps":["trace[1343124426] 'process raft request' (duration: 821.922892ms)","trace[1343124426] 'compare' (duration: 29.091584ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:48.166392Z","caller":"traceutil/trace.go:171","msg":"trace[367794558] linearizableReadLoop","detail":"{readStateIndex:25959; appliedIndex:25957; }","duration":"760.25461ms","start":"2026-09-16T22:41:47.406107Z","end":"2026-09-16T22:41:48.166361Z","steps":["trace[367794558] 'read index received' (duration: 447.732833ms)","trace[367794558] 'applied index is now lower than readState.Index' (duration: 312.520417ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:41:48.166484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:47.314874Z","time spent":"851.528917ms","remote":"127.0.0.1:57016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1873,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:41:48.16648Z","caller":"traceutil/trace.go:171","msg":"trace[2089543365] transaction","detail":"{read_only:false; response_revision:25135; number_of_response:1; }","duration":"577.783881ms","start":"2026-09-16T22:41:47.588666Z","end":"2026-09-16T22:41:48.16645Z","steps":["trace[2089543365] 'process raft request' (duration: 577.556546ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:48.166683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.552537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-16T22:41:48.166707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:47.588639Z","time spent":"578.021805ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:41:48.166733Z","caller":"traceutil/trace.go:171","msg":"trace[519652701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25135; }","duration":"760.656968ms","start":"2026-09-16T22:41:47.406063Z","end":"2026-09-16T22:41:48.16672Z","steps":["trace[519652701] 'agreement among raft nodes before linearized reading' (duration: 760.457934ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:48.166771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:47.406046Z","time spent":"760.71569ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-16T22:41:48.702084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.902769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-16T22:41:48.702228Z","caller":"traceutil/trace.go:171","msg":"trace[2111009161] transaction","detail":"{read_only:false; response_revision:25136; number_of_response:1; }","duration":"786.998834ms","start":"2026-09-16T22:41:47.9152Z","end":"2026-09-16T22:41:48.702199Z","steps":["trace[2111009161] 'process raft request' (duration: 386.889981ms)","trace[2111009161] 'compare' (duration: 399.499421ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:48.702356Z","caller":"traceutil/trace.go:171","msg":"trace[720378504] transaction","detail":"{read_only:false; response_revision:25138; number_of_response:1; }","duration":"525.737644ms","start":"2026-09-16T22:41:48.1766Z","end":"2026-09-16T22:41:48.702338Z","steps":["trace[720378504] 'process raft request' (duration: 525.678593ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:48.702371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:47.915173Z","time spent":"787.115196ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T22:41:48.702425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:48.176584Z","time spent":"525.807696ms","remote":"127.0.0.1:57016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2720,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:41:48.70239Z","caller":"traceutil/trace.go:171","msg":"trace[1281833098] linearizableReadLoop","detail":"{readStateIndex:25961; appliedIndex:25960; }","duration":"535.882112ms","start":"2026-09-16T22:41:48.166463Z","end":"2026-09-16T22:41:48.702346Z","steps":["trace[1281833098] 'read index received' (duration: 135.635486ms)","trace[1281833098] 'applied index is now lower than readState.Index' (duration: 400.217456ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:48.702507Z","caller":"traceutil/trace.go:171","msg":"trace[119996218] transaction","detail":"{read_only:false; response_revision:25137; number_of_response:1; }","duration":"530.415355ms","start":"2026-09-16T22:41:48.172021Z","end":"2026-09-16T22:41:48.702436Z","steps":["trace[119996218] 'process raft request' (duration: 530.16576ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:48.702612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197842786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:41:48.702678Z","caller":"traceutil/trace.go:171","msg":"trace[2114128274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25138; }","duration":"1.198135043s","start":"2026-09-16T22:41:47.504526Z","end":"2026-09-16T22:41:48.702661Z","steps":["trace[2114128274] 'agreement among raft nodes before linearized reading' (duration: 1.197953069s)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:48.702737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:47.504504Z","time spent":"1.198220284s","remote":"127.0.0.1:51922","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-09-16T22:41:48.702825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.770165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-09-16T22:41:48.702828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.687508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-16T22:41:48.702886Z","caller":"traceutil/trace.go:171","msg":"trace[1834238792] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25138; }","duration":"931.876048ms","start":"2026-09-16T22:41:47.770996Z","end":"2026-09-16T22:41:48.702872Z","steps":["trace[1834238792] 'agreement among raft nodes before linearized reading' (duration: 931.689894ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:48.702892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.714908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-16T22:41:48.702897Z","caller":"traceutil/trace.go:171","msg":"trace[506379898] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:25138; }","duration":"570.79433ms","start":"2026-09-16T22:41:48.132088Z","end":"2026-09-16T22:41:48.702882Z","steps":["trace[506379898] 'agreement among raft nodes before linearized reading' (duration: 570.688448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:48.702905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-09-16T22:41:48.702898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.374479ms","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-09-16T22:41:48.702928Z","caller":"traceutil/trace.go:171","msg":"trace[1364382725] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:25138; }","duration":"905.79307ms","start":"2026-09-16T22:41:47.797125Z","end":"2026-09-16T22:41:48.702918Z","steps":["trace[1364382725] 'agreement among raft nodes before linearized reading' (duration: 905.644876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:48.702918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:47.770977Z","time spent":"931.933758ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-16T22:41:48.702959Z","caller":"traceutil/trace.go:171","msg":"trace[2088096617] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25138; }","duration":"1.194765031s","start":"2026-09-16T22:41:47.508164Z","end":"2026-09-16T22:41:48.702929Z","steps":["trace[2088096617] 'agreement among raft nodes before linearized reading' (duration: 1.194646278s)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:48.702968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:48.132065Z","time spent":"570.864302ms","remote":"127.0.0.1:52172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":7,"response size":31,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-09-16T22:41:48.702985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:47.797104Z","time spent":"905.872922ms","remote":"127.0.0.1:52032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":141,"response size":32,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-09-16T22:41:48.70299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:47.508156Z","time spent":"1.194827033s","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-16T22:41:48.702987Z","caller":"traceutil/trace.go:171","msg":"trace[1741193676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25138; }","duration":"645.493722ms","start":"2026-09-16T22:41:48.057477Z","end":"2026-09-16T22:41:48.702971Z","steps":["trace[1741193676] 'agreement among raft nodes before linearized reading' (duration: 645.258116ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:48.703035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:48.057458Z","time spent":"645.563194ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-16T22:41:48.702615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:48.171998Z","time spent":"530.564178ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T22:41:48.703123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006126191s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-16T22:41:48.703148Z","caller":"traceutil/trace.go:171","msg":"trace[194878670] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25138; }","duration":"1.006179841s","start":"2026-09-16T22:41:47.696959Z","end":"2026-09-16T22:41:48.703139Z","steps":["trace[194878670] 'agreement among raft nodes before linearized reading' (duration: 1.006097579s)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:48.703136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198120833s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-09-16T22:41:48.703169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:47.696915Z","time spent":"1.006248223s","remote":"127.0.0.1:52120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-16T22:41:48.703189Z","caller":"traceutil/trace.go:171","msg":"trace[493180700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25138; }","duration":"1.198210816s","start":"2026-09-16T22:41:47.504962Z","end":"2026-09-16T22:41:48.703173Z","steps":["trace[493180700] 'agreement among raft nodes before linearized reading' (duration: 1.198043032s)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:48.703225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172297381s","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-09-16T22:41:48.703274Z","caller":"traceutil/trace.go:171","msg":"trace[426248005] 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:25138; }","duration":"1.172386463s","start":"2026-09-16T22:41:47.530872Z","end":"2026-09-16T22:41:48.703259Z","steps":["trace[426248005] 'agreement among raft nodes before linearized reading' (duration: 1.172307541s)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:48.70332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:47.530851Z","time spent":"1.172453804s","remote":"127.0.0.1:38692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-09-16T22:41:48.703233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:47.504954Z","time spent":"1.198266897s","remote":"127.0.0.1:52190","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":"info","ts":"2026-09-16T22:41:48.772517Z","caller":"traceutil/trace.go:171","msg":"trace[1518937404] transaction","detail":"{read_only:false; response_revision:25139; number_of_response:1; }","duration":"356.454698ms","start":"2026-09-16T22:41:48.416039Z","end":"2026-09-16T22:41:48.772494Z","steps":["trace[1518937404] 'process raft request' (duration: 356.299944ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:48.772664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:48.416013Z","time spent":"356.571591ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:41:48.879705Z","caller":"traceutil/trace.go:171","msg":"trace[354075792] transaction","detail":"{read_only:false; response_revision:25144; number_of_response:1; }","duration":"101.205459ms","start":"2026-09-16T22:41:48.778473Z","end":"2026-09-16T22:41:48.879679Z","steps":["trace[354075792] 'process raft request' (duration: 100.872461ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:55.483062Z","caller":"traceutil/trace.go:171","msg":"trace[951196193] transaction","detail":"{read_only:false; response_revision:25262; number_of_response:1; }","duration":"151.146169ms","start":"2026-09-16T22:41:55.331886Z","end":"2026-09-16T22:41:55.483032Z","steps":["trace[951196193] 'process raft request' (duration: 150.941675ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:55.698049Z","caller":"traceutil/trace.go:171","msg":"trace[722548339] linearizableReadLoop","detail":"{readStateIndex:26093; appliedIndex:26089; }","duration":"320.702142ms","start":"2026-09-16T22:41:55.377323Z","end":"2026-09-16T22:41:55.698025Z","steps":["trace[722548339] 'read index received' (duration: 105.517681ms)","trace[722548339] 'applied index is now lower than readState.Index' (duration: 215.183321ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:55.698083Z","caller":"traceutil/trace.go:171","msg":"trace[767809521] transaction","detail":"{read_only:false; response_revision:25263; number_of_response:1; }","duration":"364.669885ms","start":"2026-09-16T22:41:55.333381Z","end":"2026-09-16T22:41:55.69805Z","steps":["trace[767809521] 'process raft request' (duration: 266.433669ms)","trace[767809521] 'compare' (duration: 97.839766ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:41:55.698094Z","caller":"traceutil/trace.go:171","msg":"trace[1988441199] transaction","detail":"{read_only:false; response_revision:25264; number_of_response:1; }","duration":"361.685171ms","start":"2026-09-16T22:41:55.336372Z","end":"2026-09-16T22:41:55.698057Z","steps":["trace[1988441199] 'process raft request' (duration: 361.512696ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:41:55.698164Z","caller":"traceutil/trace.go:171","msg":"trace[391055239] transaction","detail":"{read_only:false; response_revision:25265; number_of_response:1; }","duration":"361.571549ms","start":"2026-09-16T22:41:55.336577Z","end":"2026-09-16T22:41:55.698148Z","steps":["trace[391055239] 'process raft request' (duration: 361.405405ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:55.69824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:55.333355Z","time spent":"364.788608ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T22:41:55.698263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:55.336547Z","time spent":"361.64425ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T22:41:55.698265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:55.336354Z","time spent":"361.810283ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T22:41:55.698428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.108791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-16T22:41:55.698464Z","caller":"traceutil/trace.go:171","msg":"trace[1169595629] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:25265; }","duration":"321.181412ms","start":"2026-09-16T22:41:55.377269Z","end":"2026-09-16T22:41:55.698451Z","steps":["trace[1169595629] 'agreement among raft nodes before linearized reading' (duration: 321.126531ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:41:55.698497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:41:55.377247Z","time spent":"321.241394ms","remote":"127.0.0.1:52108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-09-16T22:41:55.69854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.041765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:41:55.6986Z","caller":"traceutil/trace.go:171","msg":"trace[665628381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25265; }","duration":"192.100717ms","start":"2026-09-16T22:41:55.50648Z","end":"2026-09-16T22:41:55.69858Z","steps":["trace[665628381] 'agreement among raft nodes before linearized reading' (duration: 192.003355ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:27.429625Z","caller":"traceutil/trace.go:171","msg":"trace[1041856410] transaction","detail":"{read_only:false; response_revision:25519; number_of_response:1; }","duration":"451.210499ms","start":"2026-09-16T22:42:26.978391Z","end":"2026-09-16T22:42:27.429602Z","steps":["trace[1041856410] 'process raft request' (duration: 451.034685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:27.429769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:26.97835Z","time spent":"451.344252ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:42:27.446993Z","caller":"traceutil/trace.go:171","msg":"trace[783030340] transaction","detail":"{read_only:false; response_revision:25520; number_of_response:1; }","duration":"467.582409ms","start":"2026-09-16T22:42:26.979364Z","end":"2026-09-16T22:42:27.446947Z","steps":["trace[783030340] 'process raft request' (duration: 467.399825ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:27.44715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:26.979344Z","time spent":"467.722753ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:42:27.447389Z","caller":"traceutil/trace.go:171","msg":"trace[2094144820] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"155.739558ms","start":"2026-09-16T22:42:27.291622Z","end":"2026-09-16T22:42:27.447362Z","steps":["trace[2094144820] 'process raft request' (duration: 155.280148ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:27.447619Z","caller":"traceutil/trace.go:171","msg":"trace[1870285457] linearizableReadLoop","detail":"{readStateIndex:26355; appliedIndex:26353; }","duration":"449.361418ms","start":"2026-09-16T22:42:26.998245Z","end":"2026-09-16T22:42:27.447607Z","steps":["trace[1870285457] 'read index received' (duration: 431.23642ms)","trace[1870285457] 'applied index is now lower than readState.Index' (duration: 18.124148ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:42:27.447694Z","caller":"traceutil/trace.go:171","msg":"trace[406553121] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"154.248865ms","start":"2026-09-16T22:42:27.293436Z","end":"2026-09-16T22:42:27.447685Z","steps":["trace[406553121] 'process raft request' (duration: 153.836126ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:27.447854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.599843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:42:27.447908Z","caller":"traceutil/trace.go:171","msg":"trace[1052750773] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:25522; }","duration":"449.694415ms","start":"2026-09-16T22:42:26.998199Z","end":"2026-09-16T22:42:27.447894Z","steps":["trace[1052750773] 'agreement among raft nodes before linearized reading' (duration: 449.591913ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:27.447939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:26.998176Z","time spent":"449.754017ms","remote":"127.0.0.1:57108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"warn","ts":"2026-09-16T22:42:27.448261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.642462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274397"} {"level":"info","ts":"2026-09-16T22:42:27.448339Z","caller":"traceutil/trace.go:171","msg":"trace[836205277] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25522; }","duration":"342.766224ms","start":"2026-09-16T22:42:27.105554Z","end":"2026-09-16T22:42:27.44832Z","steps":["trace[836205277] 'agreement among raft nodes before linearized reading' (duration: 342.259084ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:27.448366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.605207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-16T22:42:27.448417Z","caller":"traceutil/trace.go:171","msg":"trace[1436982583] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:25522; }","duration":"150.695818ms","start":"2026-09-16T22:42:27.297708Z","end":"2026-09-16T22:42:27.448404Z","steps":["trace[1436982583] 'agreement among raft nodes before linearized reading' (duration: 150.608066ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:27.448471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.057879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-09-16T22:42:27.448498Z","caller":"traceutil/trace.go:171","msg":"trace[1791296723] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25522; }","duration":"223.116881ms","start":"2026-09-16T22:42:27.225372Z","end":"2026-09-16T22:42:27.448489Z","steps":["trace[1791296723] 'agreement among raft nodes before linearized reading' (duration: 223.027839ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:27.448377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:27.105533Z","time spent":"342.834486ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":32,"response size":274420,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"info","ts":"2026-09-16T22:42:27.712994Z","caller":"traceutil/trace.go:171","msg":"trace[2087533500] transaction","detail":"{read_only:false; response_revision:25523; number_of_response:1; }","duration":"117.471407ms","start":"2026-09-16T22:42:27.595482Z","end":"2026-09-16T22:42:27.712953Z","steps":["trace[2087533500] 'process raft request' (duration: 115.374492ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:28.078854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.535909ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-16T22:42:28.079273Z","caller":"traceutil/trace.go:171","msg":"trace[1188673091] transaction","detail":"{read_only:false; response_revision:25525; number_of_response:1; }","duration":"265.964119ms","start":"2026-09-16T22:42:27.813278Z","end":"2026-09-16T22:42:28.079242Z","steps":["trace[1188673091] 'process raft request' (duration: 265.737185ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:28.079278Z","caller":"traceutil/trace.go:171","msg":"trace[1724798947] transaction","detail":"{read_only:false; response_revision:25524; number_of_response:1; }","duration":"361.235011ms","start":"2026-09-16T22:42:27.71802Z","end":"2026-09-16T22:42:28.079255Z","steps":["trace[1724798947] 'process raft request' (duration: 173.20372ms)","trace[1724798947] 'compare' (duration: 187.337425ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:42:28.079275Z","caller":"traceutil/trace.go:171","msg":"trace[453333245] transaction","detail":"{read_only:false; response_revision:25526; number_of_response:1; }","duration":"265.889087ms","start":"2026-09-16T22:42:27.813358Z","end":"2026-09-16T22:42:28.079248Z","steps":["trace[453333245] 'process raft request' (duration: 265.764715ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:28.079315Z","caller":"traceutil/trace.go:171","msg":"trace[1765345596] transaction","detail":"{read_only:false; response_revision:25527; number_of_response:1; }","duration":"263.157299ms","start":"2026-09-16T22:42:27.816123Z","end":"2026-09-16T22:42:28.07928Z","steps":["trace[1765345596] 'process raft request' (duration: 263.055767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:28.079443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:27.718Z","time spent":"361.342722ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:42:28.458826Z","caller":"traceutil/trace.go:171","msg":"trace[495869988] transaction","detail":"{read_only:false; response_revision:25528; number_of_response:1; }","duration":"279.343136ms","start":"2026-09-16T22:42:28.179412Z","end":"2026-09-16T22:42:28.458755Z","steps":["trace[495869988] 'process raft request' (duration: 232.453511ms)","trace[495869988] 'compare' (duration: 46.749412ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:42:29.06268Z","caller":"traceutil/trace.go:171","msg":"trace[135089947] transaction","detail":"{read_only:false; response_revision:25531; number_of_response:1; }","duration":"135.31113ms","start":"2026-09-16T22:42:28.927349Z","end":"2026-09-16T22:42:29.06266Z","steps":["trace[135089947] 'process raft request' (duration: 131.584989ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:29.062912Z","caller":"traceutil/trace.go:171","msg":"trace[454116548] transaction","detail":"{read_only:false; response_revision:25532; number_of_response:1; }","duration":"133.327187ms","start":"2026-09-16T22:42:28.929549Z","end":"2026-09-16T22:42:29.062876Z","steps":["trace[454116548] 'process raft request' (duration: 133.080552ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:29.476799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.888538ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-16T22:42:29.47695Z","caller":"traceutil/trace.go:171","msg":"trace[612654894] linearizableReadLoop","detail":"{readStateIndex:26371; appliedIndex:26370; }","duration":"320.08066ms","start":"2026-09-16T22:42:29.156847Z","end":"2026-09-16T22:42:29.476927Z","steps":["trace[612654894] 'read index received' (duration: 144.026087ms)","trace[612654894] 'applied index is now lower than readState.Index' (duration: 176.051563ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:42:29.477154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.293084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:42:29.477201Z","caller":"traceutil/trace.go:171","msg":"trace[1286353890] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25535; }","duration":"320.406946ms","start":"2026-09-16T22:42:29.156777Z","end":"2026-09-16T22:42:29.477184Z","steps":["trace[1286353890] 'agreement among raft nodes before linearized reading' (duration: 320.272753ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:29.477247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:29.156764Z","time spent":"320.468507ms","remote":"127.0.0.1:52206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-09-16T22:42:29.477088Z","caller":"traceutil/trace.go:171","msg":"trace[712123497] transaction","detail":"{read_only:false; response_revision:25535; number_of_response:1; }","duration":"391.03757ms","start":"2026-09-16T22:42:29.086021Z","end":"2026-09-16T22:42:29.477058Z","steps":["trace[712123497] 'process raft request' (duration: 214.760682ms)","trace[712123497] 'compare' (duration: 175.571552ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:42:29.477401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:29.085997Z","time spent":"391.307645ms","remote":"127.0.0.1:51964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:42:29.557632Z","caller":"traceutil/trace.go:171","msg":"trace[1441648953] transaction","detail":"{read_only:false; response_revision:25537; number_of_response:1; }","duration":"103.464527ms","start":"2026-09-16T22:42:29.454126Z","end":"2026-09-16T22:42:29.557591Z","steps":["trace[1441648953] 'process raft request' (duration: 103.319594ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:29.557726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.594499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-16T22:42:29.557786Z","caller":"traceutil/trace.go:171","msg":"trace[793150205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25537; }","duration":"124.697671ms","start":"2026-09-16T22:42:29.433074Z","end":"2026-09-16T22:42:29.557771Z","steps":["trace[793150205] 'agreement among raft nodes before linearized reading' (duration: 124.558258ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:29.557727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.807527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-16T22:42:29.55786Z","caller":"traceutil/trace.go:171","msg":"trace[456460163] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25537; }","duration":"105.98411ms","start":"2026-09-16T22:42:29.451859Z","end":"2026-09-16T22:42:29.557843Z","steps":["trace[456460163] 'agreement among raft nodes before linearized reading' (duration: 105.712774ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:29.766144Z","caller":"traceutil/trace.go:171","msg":"trace[1782979205] transaction","detail":"{read_only:false; response_revision:25538; number_of_response:1; }","duration":"203.95102ms","start":"2026-09-16T22:42:29.562165Z","end":"2026-09-16T22:42:29.766116Z","steps":["trace[1782979205] 'process raft request' (duration: 166.584609ms)","trace[1782979205] 'compare' (duration: 37.247899ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:42:29.770814Z","caller":"traceutil/trace.go:171","msg":"trace[1312783624] linearizableReadLoop","detail":"{readStateIndex:26375; appliedIndex:26373; }","duration":"195.523179ms","start":"2026-09-16T22:42:29.575262Z","end":"2026-09-16T22:42:29.770785Z","steps":["trace[1312783624] 'read index received' (duration: 153.418237ms)","trace[1312783624] 'applied index is now lower than readState.Index' (duration: 42.103502ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:42:29.770874Z","caller":"traceutil/trace.go:171","msg":"trace[2142433888] transaction","detail":"{read_only:false; response_revision:25539; number_of_response:1; }","duration":"208.561049ms","start":"2026-09-16T22:42:29.562278Z","end":"2026-09-16T22:42:29.770839Z","steps":["trace[2142433888] 'process raft request' (duration: 208.272683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:29.771119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.734353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:42:29.771189Z","caller":"traceutil/trace.go:171","msg":"trace[2063055369] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:25540; }","duration":"195.933059ms","start":"2026-09-16T22:42:29.575238Z","end":"2026-09-16T22:42:29.771171Z","steps":["trace[2063055369] 'agreement among raft nodes before linearized reading' (duration: 195.693313ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:29.771096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.696462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:42:29.771332Z","caller":"traceutil/trace.go:171","msg":"trace[426248685] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25540; }","duration":"194.968328ms","start":"2026-09-16T22:42:29.576346Z","end":"2026-09-16T22:42:29.771314Z","steps":["trace[426248685] 'agreement among raft nodes before linearized reading' (duration: 194.686522ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:30.271342Z","caller":"traceutil/trace.go:171","msg":"trace[92523742] transaction","detail":"{read_only:false; response_revision:25541; number_of_response:1; }","duration":"186.632319ms","start":"2026-09-16T22:42:30.084686Z","end":"2026-09-16T22:42:30.271318Z","steps":["trace[92523742] 'process raft request' (duration: 186.479416ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:30.284261Z","caller":"traceutil/trace.go:171","msg":"trace[1225976568] transaction","detail":"{read_only:false; response_revision:25543; number_of_response:1; }","duration":"198.980863ms","start":"2026-09-16T22:42:30.08525Z","end":"2026-09-16T22:42:30.284231Z","steps":["trace[1225976568] 'process raft request' (duration: 198.870381ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:30.284329Z","caller":"traceutil/trace.go:171","msg":"trace[1299631586] transaction","detail":"{read_only:false; response_revision:25542; number_of_response:1; }","duration":"199.041905ms","start":"2026-09-16T22:42:30.085188Z","end":"2026-09-16T22:42:30.28423Z","steps":["trace[1299631586] 'process raft request' (duration: 198.695468ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:30.28448Z","caller":"traceutil/trace.go:171","msg":"trace[665294359] transaction","detail":"{read_only:false; response_revision:25544; number_of_response:1; }","duration":"137.506386ms","start":"2026-09-16T22:42:30.146951Z","end":"2026-09-16T22:42:30.284457Z","steps":["trace[665294359] 'process raft request' (duration: 137.2244ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:31.211011Z","caller":"traceutil/trace.go:171","msg":"trace[557657135] transaction","detail":"{read_only:false; response_revision:25546; number_of_response:1; }","duration":"142.747519ms","start":"2026-09-16T22:42:31.068246Z","end":"2026-09-16T22:42:31.210994Z","steps":["trace[557657135] 'process raft request' (duration: 142.635386ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:31.230523Z","caller":"traceutil/trace.go:171","msg":"trace[1347732054] transaction","detail":"{read_only:false; response_revision:25547; number_of_response:1; }","duration":"154.166863ms","start":"2026-09-16T22:42:31.076332Z","end":"2026-09-16T22:42:31.230499Z","steps":["trace[1347732054] 'process raft request' (duration: 153.844487ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:31.582717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.543692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-16T22:42:31.582857Z","caller":"traceutil/trace.go:171","msg":"trace[2017623612] transaction","detail":"{read_only:false; response_revision:25548; number_of_response:1; }","duration":"345.570314ms","start":"2026-09-16T22:42:31.237273Z","end":"2026-09-16T22:42:31.582844Z","steps":["trace[2017623612] 'process raft request' (duration: 133.836678ms)","trace[2017623612] 'compare' (duration: 211.457401ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:42:31.582897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:31.237255Z","time spent":"345.621786ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:42:31.582929Z","caller":"traceutil/trace.go:171","msg":"trace[804264149] transaction","detail":"{read_only:false; response_revision:25549; number_of_response:1; }","duration":"345.468883ms","start":"2026-09-16T22:42:31.237436Z","end":"2026-09-16T22:42:31.582904Z","steps":["trace[804264149] 'process raft request' (duration: 345.35136ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:31.583049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:31.237426Z","time spent":"345.575965ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T22:42:31.629135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.280174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:42:31.629232Z","caller":"traceutil/trace.go:171","msg":"trace[1056424546] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25551; }","duration":"104.401257ms","start":"2026-09-16T22:42:31.524791Z","end":"2026-09-16T22:42:31.629192Z","steps":["trace[1056424546] 'agreement among raft nodes before linearized reading' (duration: 104.252294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:31.875534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.646644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-16T22:42:31.876057Z","caller":"traceutil/trace.go:171","msg":"trace[794844755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25551; }","duration":"106.213296ms","start":"2026-09-16T22:42:31.769816Z","end":"2026-09-16T22:42:31.876029Z","steps":["trace[794844755] 'range keys from in-memory index tree' (duration: 105.444ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:31.893666Z","caller":"traceutil/trace.go:171","msg":"trace[1839635095] linearizableReadLoop","detail":"{readStateIndex:26388; appliedIndex:26387; }","duration":"116.216159ms","start":"2026-09-16T22:42:31.777424Z","end":"2026-09-16T22:42:31.89364Z","steps":["trace[1839635095] 'read index received' (duration: 116.043606ms)","trace[1839635095] 'applied index is now lower than readState.Index' (duration: 170.893µs)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:42:31.893884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.447395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-16T22:42:31.893918Z","caller":"traceutil/trace.go:171","msg":"trace[1598752456] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25552; }","duration":"116.535947ms","start":"2026-09-16T22:42:31.777371Z","end":"2026-09-16T22:42:31.893907Z","steps":["trace[1598752456] 'agreement among raft nodes before linearized reading' (duration: 116.361783ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:31.894274Z","caller":"traceutil/trace.go:171","msg":"trace[195868988] transaction","detail":"{read_only:false; response_revision:25552; number_of_response:1; }","duration":"118.051789ms","start":"2026-09-16T22:42:31.776211Z","end":"2026-09-16T22:42:31.894263Z","steps":["trace[195868988] 'process raft request' (duration: 117.265123ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:32.161877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.729376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-16T22:42:32.162144Z","caller":"traceutil/trace.go:171","msg":"trace[2084019720] linearizableReadLoop","detail":"{readStateIndex:26390; appliedIndex:26388; }","duration":"181.96911ms","start":"2026-09-16T22:42:31.980153Z","end":"2026-09-16T22:42:32.162122Z","steps":["trace[2084019720] 'read index received' (duration: 33.863006ms)","trace[2084019720] 'applied index is now lower than readState.Index' (duration: 148.104494ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:42:32.16228Z","caller":"traceutil/trace.go:171","msg":"trace[1343283604] transaction","detail":"{read_only:false; response_revision:25554; number_of_response:1; }","duration":"262.374383ms","start":"2026-09-16T22:42:31.899886Z","end":"2026-09-16T22:42:32.162261Z","steps":["trace[1343283604] 'process raft request' (duration: 262.145228ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:32.162311Z","caller":"traceutil/trace.go:171","msg":"trace[1793691316] transaction","detail":"{read_only:false; response_revision:25553; number_of_response:1; }","duration":"281.598764ms","start":"2026-09-16T22:42:31.880679Z","end":"2026-09-16T22:42:32.162278Z","steps":["trace[1793691316] 'process raft request' (duration: 133.366338ms)","trace[1793691316] 'compare' (duration: 147.565402ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:42:32.162421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.838332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:1 size:987"} {"level":"info","ts":"2026-09-16T22:42:32.162506Z","caller":"traceutil/trace.go:171","msg":"trace[68147867] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:1; response_revision:25554; }","duration":"108.968755ms","start":"2026-09-16T22:42:32.053517Z","end":"2026-09-16T22:42:32.162486Z","steps":["trace[68147867] 'agreement among raft nodes before linearized reading' (duration: 108.775161ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:32.162502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.327747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-09-16T22:42:32.162595Z","caller":"traceutil/trace.go:171","msg":"trace[1863295778] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:25554; }","duration":"182.47037ms","start":"2026-09-16T22:42:31.980102Z","end":"2026-09-16T22:42:32.162572Z","steps":["trace[1863295778] 'agreement among raft nodes before linearized reading' (duration: 182.204074ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:32.467626Z","caller":"traceutil/trace.go:171","msg":"trace[273118698] transaction","detail":"{read_only:false; response_revision:25555; number_of_response:1; }","duration":"187.770093ms","start":"2026-09-16T22:42:32.27983Z","end":"2026-09-16T22:42:32.4676Z","steps":["trace[273118698] 'process raft request' (duration: 139.463478ms)","trace[273118698] 'compare' (duration: 48.176582ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:42:32.766373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.924699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-16T22:42:32.766541Z","caller":"traceutil/trace.go:171","msg":"trace[1715555995] linearizableReadLoop","detail":"{readStateIndex:26393; appliedIndex:26391; }","duration":"259.240806ms","start":"2026-09-16T22:42:32.507283Z","end":"2026-09-16T22:42:32.766524Z","steps":["trace[1715555995] 'read index received' (duration: 77.061652ms)","trace[1715555995] 'applied index is now lower than readState.Index' (duration: 182.177924ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:42:32.766629Z","caller":"traceutil/trace.go:171","msg":"trace[886446273] transaction","detail":"{read_only:false; response_revision:25556; number_of_response:1; }","duration":"476.848718ms","start":"2026-09-16T22:42:32.289769Z","end":"2026-09-16T22:42:32.766617Z","steps":["trace[886446273] 'process raft request' (duration: 294.593222ms)","trace[886446273] 'compare' (duration: 181.52384ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:42:32.766691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:32.28975Z","time spent":"476.908689ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:42:32.766706Z","caller":"traceutil/trace.go:171","msg":"trace[773208934] transaction","detail":"{read_only:false; response_revision:25557; number_of_response:1; }","duration":"475.55606ms","start":"2026-09-16T22:42:32.291125Z","end":"2026-09-16T22:42:32.766681Z","steps":["trace[773208934] 'process raft request' (duration: 475.339456ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:32.766767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.239583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-16T22:42:32.766754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.43801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-16T22:42:32.7668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.614ms","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-09-16T22:42:32.766844Z","caller":"traceutil/trace.go:171","msg":"trace[1457567709] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25557; }","duration":"236.692252ms","start":"2026-09-16T22:42:32.530139Z","end":"2026-09-16T22:42:32.766831Z","steps":["trace[1457567709] 'agreement among raft nodes before linearized reading' (duration: 236.555139ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:32.766846Z","caller":"traceutil/trace.go:171","msg":"trace[1019418710] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:25557; }","duration":"259.626073ms","start":"2026-09-16T22:42:32.507197Z","end":"2026-09-16T22:42:32.766823Z","steps":["trace[1019418710] 'agreement among raft nodes before linearized reading' (duration: 259.45617ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:32.766855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:32.2911Z","time spent":"475.670403ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:42:32.76683Z","caller":"traceutil/trace.go:171","msg":"trace[922503968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25557; }","duration":"258.361676ms","start":"2026-09-16T22:42:32.508452Z","end":"2026-09-16T22:42:32.766814Z","steps":["trace[922503968] 'agreement among raft nodes before linearized reading' (duration: 258.226113ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:33.641459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.47432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:42:33.641543Z","caller":"traceutil/trace.go:171","msg":"trace[177962456] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:25560; }","duration":"245.609043ms","start":"2026-09-16T22:42:33.395911Z","end":"2026-09-16T22:42:33.641521Z","steps":["trace[177962456] 'count revisions from in-memory index tree' (duration: 245.325307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:33.910373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.982643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-16T22:42:33.910598Z","caller":"traceutil/trace.go:171","msg":"trace[1822316300] transaction","detail":"{read_only:false; response_revision:25563; number_of_response:1; }","duration":"265.018738ms","start":"2026-09-16T22:42:33.645544Z","end":"2026-09-16T22:42:33.910562Z","steps":["trace[1822316300] 'process raft request' (duration: 100.752659ms)","trace[1822316300] 'compare' (duration: 163.725177ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:42:33.910631Z","caller":"traceutil/trace.go:171","msg":"trace[1977047289] transaction","detail":"{read_only:false; response_revision:25564; number_of_response:1; }","duration":"263.946226ms","start":"2026-09-16T22:42:33.646661Z","end":"2026-09-16T22:42:33.910607Z","steps":["trace[1977047289] 'process raft request' (duration: 263.819763ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:34.087494Z","caller":"traceutil/trace.go:171","msg":"trace[885697016] transaction","detail":"{read_only:false; response_revision:25566; number_of_response:1; }","duration":"188.046039ms","start":"2026-09-16T22:42:33.899413Z","end":"2026-09-16T22:42:34.087459Z","steps":["trace[885697016] 'process raft request' (duration: 166.693612ms)","trace[885697016] 'compare' (duration: 21.03439ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:42:45.568361Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22308} {"level":"info","ts":"2026-09-16T22:42:45.816798Z","caller":"traceutil/trace.go:171","msg":"trace[188205518] transaction","detail":"{read_only:false; response_revision:25651; number_of_response:1; }","duration":"134.41429ms","start":"2026-09-16T22:42:45.68235Z","end":"2026-09-16T22:42:45.816764Z","steps":["trace[188205518] 'process raft request' (duration: 77.788817ms)","trace[188205518] 'compare' (duration: 56.427179ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:42:46.140535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.847071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:42:46.140612Z","caller":"traceutil/trace.go:171","msg":"trace[1810308252] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:25651; }","duration":"282.974984ms","start":"2026-09-16T22:42:45.857619Z","end":"2026-09-16T22:42:46.140594Z","steps":["trace[1810308252] 'count revisions from in-memory index tree' (duration: 282.756759ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:46.434917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.516992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-16T22:42:46.43512Z","caller":"traceutil/trace.go:171","msg":"trace[1474826937] transaction","detail":"{read_only:false; response_revision:25653; number_of_response:1; }","duration":"307.06086ms","start":"2026-09-16T22:42:46.128042Z","end":"2026-09-16T22:42:46.435103Z","steps":["trace[1474826937] 'process raft request' (duration: 306.989678ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:46.435186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:46.128014Z","time spent":"307.142401ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:42:46.435287Z","caller":"traceutil/trace.go:171","msg":"trace[1723313094] transaction","detail":"{read_only:false; response_revision:25652; number_of_response:1; }","duration":"504.273726ms","start":"2026-09-16T22:42:45.930997Z","end":"2026-09-16T22:42:46.43527Z","steps":["trace[1723313094] 'process raft request' (duration: 110.327314ms)","trace[1723313094] 'compare' (duration: 99.123584ms)","trace[1723313094] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; req_size:532; } (duration: 293.988729ms)"],"step_count":3} {"level":"warn","ts":"2026-09-16T22:42:46.435327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:45.930945Z","time spent":"504.360237ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:42:46.435316Z","caller":"traceutil/trace.go:171","msg":"trace[221822416] linearizableReadLoop","detail":"{readStateIndex:26492; appliedIndex:26491; }","duration":"504.075372ms","start":"2026-09-16T22:42:45.931204Z","end":"2026-09-16T22:42:46.435279Z","steps":["trace[221822416] 'read index received' (duration: 110.038648ms)","trace[221822416] 'applied index is now lower than readState.Index' (duration: 394.033494ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T22:42:46.435451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.216315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:42:46.435517Z","caller":"traceutil/trace.go:171","msg":"trace[117951000] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25653; }","duration":"504.327316ms","start":"2026-09-16T22:42:45.931173Z","end":"2026-09-16T22:42:46.435501Z","steps":["trace[117951000] 'agreement among raft nodes before linearized reading' (duration: 504.199034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:46.435555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:45.931161Z","time spent":"504.380697ms","remote":"127.0.0.1:38664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"warn","ts":"2026-09-16T22:42:46.435618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.259234ms","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-09-16T22:42:46.435665Z","caller":"traceutil/trace.go:171","msg":"trace[603952046] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25653; }","duration":"454.309475ms","start":"2026-09-16T22:42:45.981341Z","end":"2026-09-16T22:42:46.43565Z","steps":["trace[603952046] 'agreement among raft nodes before linearized reading' (duration: 454.120661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:46.435705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:45.981273Z","time spent":"454.420717ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-16T22:42:46.435886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.811462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-16T22:42:46.435932Z","caller":"traceutil/trace.go:171","msg":"trace[1775773125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25653; }","duration":"150.894093ms","start":"2026-09-16T22:42:46.285025Z","end":"2026-09-16T22:42:46.435919Z","steps":["trace[1775773125] 'agreement among raft nodes before linearized reading' (duration: 150.75728ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:46.435944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.833239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-09-16T22:42:46.435951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.045327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-16T22:42:46.436042Z","caller":"traceutil/trace.go:171","msg":"trace[1737523528] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25653; }","duration":"148.992172ms","start":"2026-09-16T22:42:46.287028Z","end":"2026-09-16T22:42:46.436021Z","steps":["trace[1737523528] 'agreement among raft nodes before linearized reading' (duration: 148.730947ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:46.436053Z","caller":"traceutil/trace.go:171","msg":"trace[1975818328] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25653; }","duration":"453.214591ms","start":"2026-09-16T22:42:45.982816Z","end":"2026-09-16T22:42:46.43603Z","steps":["trace[1975818328] 'agreement among raft nodes before linearized reading' (duration: 452.980186ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:46.436095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:45.982792Z","time spent":"453.291603ms","remote":"127.0.0.1:52190","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-09-16T22:42:46.690601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.470181ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:42:46.69073Z","caller":"traceutil/trace.go:171","msg":"trace[753586068] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25653; }","duration":"113.622724ms","start":"2026-09-16T22:42:46.577089Z","end":"2026-09-16T22:42:46.690712Z","steps":["trace[753586068] 'range keys from in-memory index tree' (duration: 113.447391ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:46.983485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.165951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-16T22:42:46.983707Z","caller":"traceutil/trace.go:171","msg":"trace[1031125048] transaction","detail":"{read_only:false; response_revision:25655; number_of_response:1; }","duration":"542.471884ms","start":"2026-09-16T22:42:46.441219Z","end":"2026-09-16T22:42:46.983691Z","steps":["trace[1031125048] 'process raft request' (duration: 542.360922ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:46.983772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:46.441194Z","time spent":"542.547945ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:42:46.983788Z","caller":"traceutil/trace.go:171","msg":"trace[1429290754] transaction","detail":"{read_only:false; response_revision:25654; number_of_response:1; }","duration":"543.104638ms","start":"2026-09-16T22:42:46.440615Z","end":"2026-09-16T22:42:46.983719Z","steps":["trace[1429290754] 'process raft request' (duration: 306.6268ms)","trace[1429290754] 'compare' (duration: 140.793248ms)","trace[1429290754] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:420; } (duration: 94.987445ms)"],"step_count":3} {"level":"info","ts":"2026-09-16T22:42:46.983876Z","caller":"traceutil/trace.go:171","msg":"trace[1883409554] transaction","detail":"{read_only:false; response_revision:25656; number_of_response:1; }","duration":"542.368472ms","start":"2026-09-16T22:42:46.4415Z","end":"2026-09-16T22:42:46.983869Z","steps":["trace[1883409554] 'process raft request' (duration: 542.143917ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:46.983921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:46.441486Z","time spent":"542.410093ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T22:42:46.98392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:46.440592Z","time spent":"543.254341ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:42:46.983936Z","caller":"traceutil/trace.go:171","msg":"trace[1548113835] linearizableReadLoop","detail":"{readStateIndex:26498; appliedIndex:26493; }","duration":"445.894565ms","start":"2026-09-16T22:42:46.538009Z","end":"2026-09-16T22:42:46.983904Z","steps":["trace[1548113835] 'read index received' (duration: 209.243393ms)","trace[1548113835] 'applied index is now lower than readState.Index' (duration: 236.649952ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T22:42:46.984095Z","caller":"traceutil/trace.go:171","msg":"trace[916944144] transaction","detail":"{read_only:false; response_revision:25658; number_of_response:1; }","duration":"458.978325ms","start":"2026-09-16T22:42:46.525109Z","end":"2026-09-16T22:42:46.984087Z","steps":["trace[916944144] 'process raft request' (duration: 458.75254ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:46.984065Z","caller":"traceutil/trace.go:171","msg":"trace[791298118] transaction","detail":"{read_only:false; response_revision:25657; number_of_response:1; }","duration":"542.486705ms","start":"2026-09-16T22:42:46.441567Z","end":"2026-09-16T22:42:46.984054Z","steps":["trace[791298118] 'process raft request' (duration: 542.215899ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:46.984137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:46.525079Z","time spent":"459.034296ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T22:42:46.984145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.14173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-16T22:42:46.984178Z","caller":"traceutil/trace.go:171","msg":"trace[2054982830] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:25658; }","duration":"446.230871ms","start":"2026-09-16T22:42:46.537936Z","end":"2026-09-16T22:42:46.984166Z","steps":["trace[2054982830] 'agreement among raft nodes before linearized reading' (duration: 446.15646ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:46.98421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:46.537913Z","time spent":"446.288633ms","remote":"127.0.0.1:36034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true "} {"level":"warn","ts":"2026-09-16T22:42:46.984223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T22:42:46.441552Z","time spent":"542.586767ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T22:42:46.990089Z","caller":"traceutil/trace.go:171","msg":"trace[1528383958] transaction","detail":"{read_only:false; response_revision:25659; number_of_response:1; }","duration":"159.693402ms","start":"2026-09-16T22:42:46.830373Z","end":"2026-09-16T22:42:46.990066Z","steps":["trace[1528383958] 'process raft request' (duration: 159.083539ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T22:42:46.990248Z","caller":"traceutil/trace.go:171","msg":"trace[1170805852] transaction","detail":"{read_only:false; response_revision:25660; number_of_response:1; }","duration":"156.903242ms","start":"2026-09-16T22:42:46.833335Z","end":"2026-09-16T22:42:46.990238Z","steps":["trace[1170805852] 'process raft request' (duration: 156.234858ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T22:42:47.227586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.804587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-16T22:42:47.227913Z","caller":"traceutil/trace.go:171","msg":"trace[531482137] transaction","detail":"{read_only:false; response_revision:25661; number_of_response:1; }","duration":"233.946222ms","start":"2026-09-16T22:42:46.99395Z","end":"2026-09-16T22:42:47.227897Z","steps":["trace[531482137] 'process raft request' (duration: 36.768937ms)","trace[531482137] 'compare' (duration: 91.419769ms)","trace[531482137] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-controller-manager; req_size:487; } (duration: 105.100282ms)"],"step_count":3} {"level":"info","ts":"2026-09-16T22:42:47.321457Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22308,"took":"1.749777763s","hash":1685206495,"current-db-size-bytes":55230464,"current-db-size":"55 MB","current-db-size-in-use-bytes":44982272,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-09-16T22:42:47.321518Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1685206495,"revision":22308,"compact-revision":20005} {"level":"info","ts":"2026-09-16T22:43:58.655405Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a68.wal"} {"level":"info","ts":"2026-09-16T22:47:45.577061Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25650} {"level":"info","ts":"2026-09-16T22:47:45.739641Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25650,"took":"159.413402ms","hash":521148479,"current-db-size-bytes":55652352,"current-db-size":"56 MB","current-db-size-in-use-bytes":35352576,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-16T22:47:45.739694Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":521148479,"revision":25650,"compact-revision":22308} {"level":"info","ts":"2026-09-16T22:49:00.825684Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d9b565cb482924cf","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-16T22:49:00.833463Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-16T22:49:00.833602Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}