{"level":"warn","ts":"2026-10-26T19:14:11.274141Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T19:14:11.274285Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.148: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.148:2380","--initial-cluster=instance=https://162.253.55.148:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.148:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.148: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-10-26T19:14:11.274395Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T19:14:11.274416Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.148:2380"]} {"level":"info","ts":"2026-10-26T19:14:11.274441Z","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-10-26T19:14:11.27526Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.148:2379"]} {"level":"info","ts":"2026-10-26T19:14:11.275375Z","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.148:2380"],"listen-peer-urls":["https://162.253.55.148:2380"],"advertise-client-urls":["https://162.253.55.148:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.148:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.148: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-10-26T19:14:11.280619Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.943495ms"} {"level":"info","ts":"2026-10-26T19:14:11.28772Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a6342599e225e01b","cluster-id":"8ad3da7aa894cc05"} {"level":"info","ts":"2026-10-26T19:14:11.287831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T19:14:11.287879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became follower at term 0"} {"level":"info","ts":"2026-10-26T19:14:11.287898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a6342599e225e01b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T19:14:11.287916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became follower at term 1"} {"level":"info","ts":"2026-10-26T19:14:11.287971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b switched to configuration voters=(11976238651939348507)"} {"level":"warn","ts":"2026-10-26T19:14:11.296272Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T19:14:11.302146Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T19:14:11.303837Z","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-10-26T19:14:11.308482Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a6342599e225e01b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T19:14:11.30875Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a6342599e225e01b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T19:14:11.30883Z","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-10-26T19:14:11.308979Z","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-10-26T19:14:11.309006Z","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-10-26T19:14:11.310956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b switched to configuration voters=(11976238651939348507)"} {"level":"info","ts":"2026-10-26T19:14:11.312635Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ad3da7aa894cc05","local-member-id":"a6342599e225e01b","added-peer-id":"a6342599e225e01b","added-peer-peer-urls":["https://162.253.55.148:2380"]} {"level":"info","ts":"2026-10-26T19:14:11.319694Z","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-10-26T19:14:11.319953Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a6342599e225e01b","initial-advertise-peer-urls":["https://162.253.55.148:2380"],"listen-peer-urls":["https://162.253.55.148:2380"],"advertise-client-urls":["https://162.253.55.148:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.148:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T19:14:11.319997Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T19:14:11.320104Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.148:2380"} {"level":"info","ts":"2026-10-26T19:14:11.320126Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.148:2380"} {"level":"info","ts":"2026-10-26T19:14:12.088752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T19:14:12.088816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T19:14:12.088852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b received MsgPreVoteResp from a6342599e225e01b at term 1"} {"level":"info","ts":"2026-10-26T19:14:12.088871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became candidate at term 2"} {"level":"info","ts":"2026-10-26T19:14:12.088883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b received MsgVoteResp from a6342599e225e01b at term 2"} {"level":"info","ts":"2026-10-26T19:14:12.088899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became leader at term 2"} {"level":"info","ts":"2026-10-26T19:14:12.088918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a6342599e225e01b elected leader a6342599e225e01b at term 2"} {"level":"info","ts":"2026-10-26T19:14:12.090377Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:14:12.09064Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a6342599e225e01b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.148:2379]}","request-path":"/0/members/a6342599e225e01b/attributes","cluster-id":"8ad3da7aa894cc05","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T19:14:12.090641Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T19:14:12.090717Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T19:14:12.091036Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T19:14:12.091062Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T19:14:12.091701Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ad3da7aa894cc05","local-member-id":"a6342599e225e01b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:14:12.091814Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:14:12.091857Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:14:12.094065Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.148:2379"} {"level":"info","ts":"2026-10-26T19:14:12.094083Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T19:14:30.089753Z","caller":"traceutil/trace.go:171","msg":"trace[1962887758] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"162.575108ms","start":"2026-10-26T19:14:29.927142Z","end":"2026-10-26T19:14:30.089717Z","steps":["trace[1962887758] 'process raft request' (duration: 162.382354ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:14:30.448804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.058836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-10-26T19:14:30.44893Z","caller":"traceutil/trace.go:171","msg":"trace[1190955677] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:417; }","duration":"297.22975ms","start":"2026-10-26T19:14:30.151677Z","end":"2026-10-26T19:14:30.448906Z","steps":["trace[1190955677] 'range keys from in-memory index tree' (duration: 296.963593ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:14:30.644421Z","caller":"traceutil/trace.go:171","msg":"trace[1453140180] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"192.127987ms","start":"2026-10-26T19:14:30.452272Z","end":"2026-10-26T19:14:30.6444Z","steps":["trace[1453140180] 'process raft request' (duration: 192.016304ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:14:30.822875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.662332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:14:30.823183Z","caller":"traceutil/trace.go:171","msg":"trace[520789863] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"370.419563ms","start":"2026-10-26T19:14:30.452657Z","end":"2026-10-26T19:14:30.823076Z","steps":["trace[520789863] 'process raft request' (duration: 264.89621ms)","trace[520789863] 'compare' (duration: 104.517028ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:14:30.823474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:14:30.452623Z","time spent":"370.703781ms","remote":"127.0.0.1:59188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:15:01.766037Z","caller":"traceutil/trace.go:171","msg":"trace[663280061] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"233.255594ms","start":"2026-10-26T19:15:01.532754Z","end":"2026-10-26T19:15:01.766009Z","steps":["trace[663280061] 'process raft request' (duration: 158.457998ms)","trace[663280061] 'compare' (duration: 74.662693ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:15:02.68639Z","caller":"traceutil/trace.go:171","msg":"trace[1632536563] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"446.778928ms","start":"2026-10-26T19:15:02.239548Z","end":"2026-10-26T19:15:02.686327Z","steps":["trace[1632536563] 'read index received' (duration: 440.969394ms)","trace[1632536563] 'applied index is now lower than readState.Index' (duration: 5.808494ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:15:02.686434Z","caller":"traceutil/trace.go:171","msg":"trace[865604868] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"507.746601ms","start":"2026-10-26T19:15:02.178639Z","end":"2026-10-26T19:15:02.686385Z","steps":["trace[865604868] 'process raft request' (duration: 502.157892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:15:02.686569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.893767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:15:02.686603Z","caller":"traceutil/trace.go:171","msg":"trace[1656505373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"328.983069ms","start":"2026-10-26T19:15:02.357609Z","end":"2026-10-26T19:15:02.686592Z","steps":["trace[1656505373] 'agreement among raft nodes before linearized reading' (duration: 328.904397ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:15:02.686601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:15:02.178602Z","time spent":"507.912165ms","remote":"127.0.0.1:59020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036273,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:15:02.686635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:15:02.357589Z","time spent":"329.03895ms","remote":"127.0.0.1:58896","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-10-26T19:15:02.686665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.091676ms","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-10-26T19:15:02.686752Z","caller":"traceutil/trace.go:171","msg":"trace[145661508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"447.187678ms","start":"2026-10-26T19:15:02.239542Z","end":"2026-10-26T19:15:02.686729Z","steps":["trace[145661508] 'agreement among raft nodes before linearized reading' (duration: 446.942752ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:15:02.686798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:15:02.239483Z","time spent":"447.306112ms","remote":"127.0.0.1:59188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-26T19:15:18.922947Z","caller":"traceutil/trace.go:171","msg":"trace[1295560859] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:847; }","duration":"151.688571ms","start":"2026-10-26T19:15:18.771211Z","end":"2026-10-26T19:15:18.922899Z","steps":["trace[1295560859] 'read index received' (duration: 151.487886ms)","trace[1295560859] 'applied index is now lower than readState.Index' (duration: 199.795µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:15:18.923076Z","caller":"traceutil/trace.go:171","msg":"trace[865183459] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"155.986358ms","start":"2026-10-26T19:15:18.767028Z","end":"2026-10-26T19:15:18.923015Z","steps":["trace[865183459] 'process raft request' (duration: 155.719102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:15:18.923247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.0232ms","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-10-26T19:15:18.923309Z","caller":"traceutil/trace.go:171","msg":"trace[397651609] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:816; }","duration":"152.138343ms","start":"2026-10-26T19:15:18.771157Z","end":"2026-10-26T19:15:18.923295Z","steps":["trace[397651609] 'agreement among raft nodes before linearized reading' (duration: 151.901857ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:15:49.723626Z","caller":"traceutil/trace.go:171","msg":"trace[2085999116] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"105.976438ms","start":"2026-10-26T19:15:49.617616Z","end":"2026-10-26T19:15:49.723593Z","steps":["trace[2085999116] 'process raft request' (duration: 105.812783ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:15:50.208867Z","caller":"traceutil/trace.go:171","msg":"trace[1013482542] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"340.941494ms","start":"2026-10-26T19:15:49.867877Z","end":"2026-10-26T19:15:50.208819Z","steps":["trace[1013482542] 'process raft request' (duration: 339.087309ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:15:50.209221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:15:49.867848Z","time spent":"341.277933ms","remote":"127.0.0.1:59188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:16:36.65121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.841174ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:16:36.651342Z","caller":"traceutil/trace.go:171","msg":"trace[2077077653] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1867; }","duration":"217.986968ms","start":"2026-10-26T19:16:36.433336Z","end":"2026-10-26T19:16:36.651323Z","steps":["trace[2077077653] 'range keys from in-memory index tree' (duration: 217.824194ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:16:36.651393Z","caller":"traceutil/trace.go:171","msg":"trace[1786943271] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1929; }","duration":"231.468124ms","start":"2026-10-26T19:16:36.419871Z","end":"2026-10-26T19:16:36.651339Z","steps":["trace[1786943271] 'read index received' (duration: 167.499672ms)","trace[1786943271] 'applied index is now lower than readState.Index' (duration: 63.966162ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:16:36.651574Z","caller":"traceutil/trace.go:171","msg":"trace[801492240] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"255.973715ms","start":"2026-10-26T19:16:36.395587Z","end":"2026-10-26T19:16:36.65156Z","steps":["trace[801492240] 'process raft request' (duration: 191.725295ms)","trace[801492240] 'compare' (duration: 63.680136ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:16:36.651764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.871464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-10-26T19:16:36.651863Z","caller":"traceutil/trace.go:171","msg":"trace[1385443948] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1868; }","duration":"231.992837ms","start":"2026-10-26T19:16:36.41984Z","end":"2026-10-26T19:16:36.651832Z","steps":["trace[1385443948] 'agreement among raft nodes before linearized reading' (duration: 231.70053ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:16:36.846249Z","caller":"traceutil/trace.go:171","msg":"trace[1506562854] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1931; }","duration":"108.322557ms","start":"2026-10-26T19:16:36.737889Z","end":"2026-10-26T19:16:36.846212Z","steps":["trace[1506562854] 'read index received' (duration: 108.086981ms)","trace[1506562854] 'applied index is now lower than readState.Index' (duration: 234.376µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:16:36.846416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.500882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:16:36.846466Z","caller":"traceutil/trace.go:171","msg":"trace[1056147939] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"186.711149ms","start":"2026-10-26T19:16:36.659718Z","end":"2026-10-26T19:16:36.846429Z","steps":["trace[1056147939] 'process raft request' (duration: 186.414262ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:16:36.8465Z","caller":"traceutil/trace.go:171","msg":"trace[1361635669] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1871; }","duration":"108.613555ms","start":"2026-10-26T19:16:36.737844Z","end":"2026-10-26T19:16:36.846458Z","steps":["trace[1361635669] 'agreement among raft nodes before linearized reading' (duration: 108.506562ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:16:36.846583Z","caller":"traceutil/trace.go:171","msg":"trace[764082517] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"189.367965ms","start":"2026-10-26T19:16:36.657015Z","end":"2026-10-26T19:16:36.846383Z","steps":["trace[764082517] 'process raft request' (duration: 188.960685ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:17:19.263793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.354613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-10-26T19:17:19.263896Z","caller":"traceutil/trace.go:171","msg":"trace[1462537052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2207; }","duration":"230.476315ms","start":"2026-10-26T19:17:19.033397Z","end":"2026-10-26T19:17:19.263874Z","steps":["trace[1462537052] 'range keys from in-memory index tree' (duration: 230.078206ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:17:19.412372Z","caller":"traceutil/trace.go:171","msg":"trace[649877124] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"144.299415ms","start":"2026-10-26T19:17:19.268049Z","end":"2026-10-26T19:17:19.412349Z","steps":["trace[649877124] 'process raft request' (duration: 144.168872ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:17:56.218479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.725063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T19:17:56.218565Z","caller":"traceutil/trace.go:171","msg":"trace[91426506] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2517; }","duration":"134.878937ms","start":"2026-10-26T19:17:56.083665Z","end":"2026-10-26T19:17:56.218544Z","steps":["trace[91426506] 'count revisions from in-memory index tree' (duration: 134.621861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:17:56.218476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.365287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T19:17:56.218624Z","caller":"traceutil/trace.go:171","msg":"trace[1395347014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2517; }","duration":"205.559892ms","start":"2026-10-26T19:17:56.013046Z","end":"2026-10-26T19:17:56.218606Z","steps":["trace[1395347014] 'range keys from in-memory index tree' (duration: 205.181162ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:18:21.22861Z","caller":"traceutil/trace.go:171","msg":"trace[1550939772] transaction","detail":"{read_only:false; response_revision:2688; number_of_response:1; }","duration":"144.853092ms","start":"2026-10-26T19:18:21.083732Z","end":"2026-10-26T19:18:21.228585Z","steps":["trace[1550939772] 'process raft request' (duration: 144.716319ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:18:26.562266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.429774ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:18:26.562441Z","caller":"traceutil/trace.go:171","msg":"trace[1444801448] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2727; }","duration":"128.631368ms","start":"2026-10-26T19:18:26.433787Z","end":"2026-10-26T19:18:26.562419Z","steps":["trace[1444801448] 'range keys from in-memory index tree' (duration: 128.411394ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:20:51.610264Z","caller":"traceutil/trace.go:171","msg":"trace[207854649] linearizableReadLoop","detail":"{readStateIndex:4606; appliedIndex:4605; }","duration":"114.994583ms","start":"2026-10-26T19:20:51.495244Z","end":"2026-10-26T19:20:51.610239Z","steps":["trace[207854649] 'read index received' (duration: 114.845759ms)","trace[207854649] 'applied index is now lower than readState.Index' (duration: 147.814µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:20:51.610388Z","caller":"traceutil/trace.go:171","msg":"trace[632375997] transaction","detail":"{read_only:false; response_revision:4413; number_of_response:1; }","duration":"153.534426ms","start":"2026-10-26T19:20:51.456817Z","end":"2026-10-26T19:20:51.610352Z","steps":["trace[632375997] 'process raft request' (duration: 153.269539ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:20:51.61049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.23341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-baf509f2-9f49-43ec-851b-b07907578277\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-10-26T19:20:51.610556Z","caller":"traceutil/trace.go:171","msg":"trace[1152747464] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-baf509f2-9f49-43ec-851b-b07907578277; range_end:; response_count:1; response_revision:4413; }","duration":"115.334882ms","start":"2026-10-26T19:20:51.495206Z","end":"2026-10-26T19:20:51.610541Z","steps":["trace[1152747464] 'agreement among raft nodes before linearized reading' (duration: 115.171427ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:20:51.619772Z","caller":"traceutil/trace.go:171","msg":"trace[862509295] transaction","detail":"{read_only:false; response_revision:4414; number_of_response:1; }","duration":"124.095675ms","start":"2026-10-26T19:20:51.495629Z","end":"2026-10-26T19:20:51.619724Z","steps":["trace[862509295] 'process raft request' (duration: 123.636383ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:20:51.62028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.262008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11710"} {"level":"info","ts":"2026-10-26T19:20:51.620393Z","caller":"traceutil/trace.go:171","msg":"trace[9378084] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4414; }","duration":"109.386621ms","start":"2026-10-26T19:20:51.510981Z","end":"2026-10-26T19:20:51.620367Z","steps":["trace[9378084] 'agreement among raft nodes before linearized reading' (duration: 109.111524ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:21:04.232648Z","caller":"traceutil/trace.go:171","msg":"trace[785119464] linearizableReadLoop","detail":"{readStateIndex:4953; appliedIndex:4951; }","duration":"116.41352ms","start":"2026-10-26T19:21:04.116203Z","end":"2026-10-26T19:21:04.232617Z","steps":["trace[785119464] 'read index received' (duration: 34.281445ms)","trace[785119464] 'applied index is now lower than readState.Index' (duration: 82.130105ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:21:04.232722Z","caller":"traceutil/trace.go:171","msg":"trace[386088218] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"170.695154ms","start":"2026-10-26T19:21:04.061993Z","end":"2026-10-26T19:21:04.232688Z","steps":["trace[386088218] 'process raft request' (duration: 133.150746ms)","trace[386088218] 'compare' (duration: 37.287921ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:21:04.233062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.83063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-10-26T19:21:04.23351Z","caller":"traceutil/trace.go:171","msg":"trace[668344117] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4747; }","duration":"117.352493ms","start":"2026-10-26T19:21:04.11613Z","end":"2026-10-26T19:21:04.233482Z","steps":["trace[668344117] 'agreement among raft nodes before linearized reading' (duration: 116.650416ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:21:13.554067Z","caller":"traceutil/trace.go:171","msg":"trace[40980470] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"144.755462ms","start":"2026-10-26T19:21:13.409274Z","end":"2026-10-26T19:21:13.55403Z","steps":["trace[40980470] 'process raft request' (duration: 96.523661ms)","trace[40980470] 'compare' (duration: 47.558734ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:21:14.029264Z","caller":"traceutil/trace.go:171","msg":"trace[728288183] linearizableReadLoop","detail":"{readStateIndex:5113; appliedIndex:5112; }","duration":"178.533654ms","start":"2026-10-26T19:21:13.850707Z","end":"2026-10-26T19:21:14.02924Z","steps":["trace[728288183] 'read index received' (duration: 178.487793ms)","trace[728288183] 'applied index is now lower than readState.Index' (duration: 44.701µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:21:14.029396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.670557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:21:14.029427Z","caller":"traceutil/trace.go:171","msg":"trace[1781727004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4901; }","duration":"178.784951ms","start":"2026-10-26T19:21:13.850633Z","end":"2026-10-26T19:21:14.029418Z","steps":["trace[1781727004] 'agreement among raft nodes before linearized reading' (duration: 178.703679ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:21:14.02937Z","caller":"traceutil/trace.go:171","msg":"trace[966471748] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"280.847753ms","start":"2026-10-26T19:21:13.748465Z","end":"2026-10-26T19:21:14.029312Z","steps":["trace[966471748] 'process raft request' (duration: 280.629637ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:21:21.097424Z","caller":"traceutil/trace.go:171","msg":"trace[2111926000] transaction","detail":"{read_only:false; response_revision:5069; number_of_response:1; }","duration":"204.707782ms","start":"2026-10-26T19:21:20.892674Z","end":"2026-10-26T19:21:21.097381Z","steps":["trace[2111926000] 'process raft request' (duration: 204.372613ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:21:21.319445Z","caller":"traceutil/trace.go:171","msg":"trace[1943965889] linearizableReadLoop","detail":"{readStateIndex:5288; appliedIndex:5286; }","duration":"323.605693ms","start":"2026-10-26T19:21:20.995812Z","end":"2026-10-26T19:21:21.319418Z","steps":["trace[1943965889] 'read index received' (duration: 101.236992ms)","trace[1943965889] 'applied index is now lower than readState.Index' (duration: 222.367211ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:21:21.319571Z","caller":"traceutil/trace.go:171","msg":"trace[105512686] transaction","detail":"{read_only:false; response_revision:5070; number_of_response:1; }","duration":"423.0182ms","start":"2026-10-26T19:21:20.896538Z","end":"2026-10-26T19:21:21.319557Z","steps":["trace[105512686] 'process raft request' (duration: 371.151108ms)","trace[105512686] 'compare' (duration: 51.613795ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:21:21.319682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:21:20.896525Z","time spent":"423.070232ms","remote":"127.0.0.1:59188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:21:21.319994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.148428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-26T19:21:21.320097Z","caller":"traceutil/trace.go:171","msg":"trace[1080358819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5070; }","duration":"324.330572ms","start":"2026-10-26T19:21:20.995741Z","end":"2026-10-26T19:21:21.320072Z","steps":["trace[1080358819] 'agreement among raft nodes before linearized reading' (duration: 324.000774ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:21:21.320136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:21:20.995715Z","time spent":"324.412395ms","remote":"127.0.0.1:59188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T19:21:21.321005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.304778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2588692"} {"level":"info","ts":"2026-10-26T19:21:21.321095Z","caller":"traceutil/trace.go:171","msg":"trace[181234895] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5070; }","duration":"307.457552ms","start":"2026-10-26T19:21:21.013617Z","end":"2026-10-26T19:21:21.321075Z","steps":["trace[181234895] 'agreement among raft nodes before linearized reading' (duration: 306.116177ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:21:21.321139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:21:21.013602Z","time spent":"307.526594ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2588716,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-10-26T19:21:32.962045Z","caller":"traceutil/trace.go:171","msg":"trace[1908858014] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"104.819769ms","start":"2026-10-26T19:21:32.857173Z","end":"2026-10-26T19:21:32.961993Z","steps":["trace[1908858014] 'process raft request' (duration: 39.13541ms)","trace[1908858014] 'compare' (duration: 65.239447ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:21:33.132668Z","caller":"traceutil/trace.go:171","msg":"trace[583494543] transaction","detail":"{read_only:false; response_revision:5208; number_of_response:1; }","duration":"101.920613ms","start":"2026-10-26T19:21:33.030715Z","end":"2026-10-26T19:21:33.132636Z","steps":["trace[583494543] 'process raft request' (duration: 65.549095ms)","trace[583494543] 'compare' (duration: 36.157463ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:22:10.763191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.358021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-10-26T19:22:10.763291Z","caller":"traceutil/trace.go:171","msg":"trace[1983687454] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5599; }","duration":"121.487854ms","start":"2026-10-26T19:22:10.64178Z","end":"2026-10-26T19:22:10.763267Z","steps":["trace[1983687454] 'agreement among raft nodes before linearized reading' (duration: 39.894219ms)","trace[1983687454] 'range keys from in-memory index tree' (duration: 81.427541ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:22:10.763379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.017127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-0\" ","response":"range_response_count:1 size:6207"} {"level":"info","ts":"2026-10-26T19:22:10.764139Z","caller":"traceutil/trace.go:171","msg":"trace[1120008464] range","detail":"{range_begin:/registry/pods/monitoring/loki-0; range_end:; response_count:1; response_revision:5599; }","duration":"102.805697ms","start":"2026-10-26T19:22:10.661301Z","end":"2026-10-26T19:22:10.764107Z","steps":["trace[1120008464] 'agreement among raft nodes before linearized reading' (duration: 20.33432ms)","trace[1120008464] 'range keys from in-memory index tree' (duration: 81.599304ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:24:12.120448Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3188} {"level":"info","ts":"2026-10-26T19:24:12.2232Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3188,"took":"100.935639ms","hash":1792554640,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":33882112,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-26T19:24:12.223297Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1792554640,"revision":3188,"compact-revision":-1} {"level":"info","ts":"2026-10-26T19:27:18.670936Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000021e4.wal"} {"level":"info","ts":"2026-10-26T19:27:35.586496Z","caller":"traceutil/trace.go:171","msg":"trace[1622172350] linearizableReadLoop","detail":"{readStateIndex:8801; appliedIndex:8800; }","duration":"227.295088ms","start":"2026-10-26T19:27:35.359164Z","end":"2026-10-26T19:27:35.586459Z","steps":["trace[1622172350] 'read index received' (duration: 226.98071ms)","trace[1622172350] 'applied index is now lower than readState.Index' (duration: 312.418µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:27:35.586628Z","caller":"traceutil/trace.go:171","msg":"trace[1961204588] transaction","detail":"{read_only:false; response_revision:8421; number_of_response:1; }","duration":"247.501357ms","start":"2026-10-26T19:27:35.339087Z","end":"2026-10-26T19:27:35.586588Z","steps":["trace[1961204588] 'process raft request' (duration: 247.147548ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:35.586723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.543094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:27:35.586776Z","caller":"traceutil/trace.go:171","msg":"trace[2144013045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8421; }","duration":"227.671118ms","start":"2026-10-26T19:27:35.359093Z","end":"2026-10-26T19:27:35.586764Z","steps":["trace[2144013045] 'agreement among raft nodes before linearized reading' (duration: 227.559005ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:35.586915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.975833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-26T19:27:35.586991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.390859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.148\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-26T19:27:35.586999Z","caller":"traceutil/trace.go:171","msg":"trace[362920370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8421; }","duration":"226.100727ms","start":"2026-10-26T19:27:35.36088Z","end":"2026-10-26T19:27:35.586981Z","steps":["trace[362920370] 'agreement among raft nodes before linearized reading' (duration: 225.82625ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:35.587035Z","caller":"traceutil/trace.go:171","msg":"trace[225361215] range","detail":"{range_begin:/registry/masterleases/162.253.55.148; range_end:; response_count:1; response_revision:8421; }","duration":"194.462441ms","start":"2026-10-26T19:27:35.392561Z","end":"2026-10-26T19:27:35.587023Z","steps":["trace[225361215] 'agreement among raft nodes before linearized reading' (duration: 194.267776ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:35.587113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.726126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-26T19:27:35.587301Z","caller":"traceutil/trace.go:171","msg":"trace[1399177322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8421; }","duration":"218.83572ms","start":"2026-10-26T19:27:35.368339Z","end":"2026-10-26T19:27:35.587175Z","steps":["trace[1399177322] 'agreement among raft nodes before linearized reading' (duration: 218.494871ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:35.822831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.97924ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-10-26T19:27:35.823259Z","caller":"traceutil/trace.go:171","msg":"trace[1087532239] transaction","detail":"{read_only:false; response_revision:8423; number_of_response:1; }","duration":"230.443519ms","start":"2026-10-26T19:27:35.592785Z","end":"2026-10-26T19:27:35.823228Z","steps":["trace[1087532239] 'process raft request' (duration: 230.355977ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:35.823438Z","caller":"traceutil/trace.go:171","msg":"trace[513649465] transaction","detail":"{read_only:false; response_revision:8424; number_of_response:1; }","duration":"162.028196ms","start":"2026-10-26T19:27:35.661383Z","end":"2026-10-26T19:27:35.823412Z","steps":["trace[513649465] 'process raft request' (duration: 161.79924ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:35.823367Z","caller":"traceutil/trace.go:171","msg":"trace[374169319] transaction","detail":"{read_only:false; response_revision:8422; number_of_response:1; }","duration":"231.101447ms","start":"2026-10-26T19:27:35.592174Z","end":"2026-10-26T19:27:35.823276Z","steps":["trace[374169319] 'process raft request' (duration: 230.793339ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:51.536995Z","caller":"traceutil/trace.go:171","msg":"trace[1615606506] transaction","detail":"{read_only:false; response_revision:8528; number_of_response:1; }","duration":"121.788819ms","start":"2026-10-26T19:27:51.415175Z","end":"2026-10-26T19:27:51.536964Z","steps":["trace[1615606506] 'process raft request' (duration: 117.512809ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:51.53698Z","caller":"traceutil/trace.go:171","msg":"trace[1333191872] linearizableReadLoop","detail":"{readStateIndex:8915; appliedIndex:8914; }","duration":"120.810604ms","start":"2026-10-26T19:27:51.416097Z","end":"2026-10-26T19:27:51.536907Z","steps":["trace[1333191872] 'read index received' (duration: 116.551774ms)","trace[1333191872] 'applied index is now lower than readState.Index' (duration: 4.25692ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:27:51.537288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.160793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-10-26T19:27:51.537397Z","caller":"traceutil/trace.go:171","msg":"trace[1886000974] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8528; }","duration":"121.307827ms","start":"2026-10-26T19:27:51.416049Z","end":"2026-10-26T19:27:51.537357Z","steps":["trace[1886000974] 'agreement among raft nodes before linearized reading' (duration: 121.027299ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:28:17.995861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.878173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:28:17.996193Z","caller":"traceutil/trace.go:171","msg":"trace[1479964906] linearizableReadLoop","detail":"{readStateIndex:9195; appliedIndex:9193; }","duration":"149.560925ms","start":"2026-10-26T19:28:17.84661Z","end":"2026-10-26T19:28:17.996171Z","steps":["trace[1479964906] 'read index received' (duration: 141.865766ms)","trace[1479964906] 'applied index is now lower than readState.Index' (duration: 7.693759ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:28:17.996311Z","caller":"traceutil/trace.go:171","msg":"trace[1714260986] transaction","detail":"{read_only:false; response_revision:8793; number_of_response:1; }","duration":"338.499144ms","start":"2026-10-26T19:28:17.657763Z","end":"2026-10-26T19:28:17.996262Z","steps":["trace[1714260986] 'process raft request' (duration: 25.087447ms)","trace[1714260986] 'compare' (duration: 312.7377ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:28:17.99634Z","caller":"traceutil/trace.go:171","msg":"trace[1718263769] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8794; }","duration":"337.99115ms","start":"2026-10-26T19:28:17.658271Z","end":"2026-10-26T19:28:17.996262Z","steps":["trace[1718263769] 'process raft request' (duration: 337.808356ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:28:17.996537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:28:17.657745Z","time spent":"338.682878ms","remote":"127.0.0.1:59112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4740,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:28:17.996592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.501624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-10-26T19:28:17.996684Z","caller":"traceutil/trace.go:171","msg":"trace[1668831254] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8794; }","duration":"113.619458ms","start":"2026-10-26T19:28:17.883042Z","end":"2026-10-26T19:28:17.996661Z","steps":["trace[1668831254] 'agreement among raft nodes before linearized reading' (duration: 113.413492ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:28:17.996673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.598983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-10-26T19:28:17.996726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.038336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-26T19:28:17.996813Z","caller":"traceutil/trace.go:171","msg":"trace[227942663] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8794; }","duration":"114.812058ms","start":"2026-10-26T19:28:17.881977Z","end":"2026-10-26T19:28:17.996789Z","steps":["trace[227942663] 'agreement among raft nodes before linearized reading' (duration: 114.410208ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:28:17.996731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:28:17.658248Z","time spent":"338.250607ms","remote":"127.0.0.1:41272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":42,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-10-26T19:28:17.996966Z","caller":"traceutil/trace.go:171","msg":"trace[119150080] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8794; }","duration":"150.352424ms","start":"2026-10-26T19:28:17.846581Z","end":"2026-10-26T19:28:17.996933Z","steps":["trace[119150080] 'agreement among raft nodes before linearized reading' (duration: 149.671897ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:18.194742Z","caller":"traceutil/trace.go:171","msg":"trace[310554241] linearizableReadLoop","detail":"{readStateIndex:9196; appliedIndex:9195; }","duration":"191.038384ms","start":"2026-10-26T19:28:18.00367Z","end":"2026-10-26T19:28:18.194708Z","steps":["trace[310554241] 'read index received' (duration: 159.926232ms)","trace[310554241] 'applied index is now lower than readState.Index' (duration: 31.110181ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:28:18.194808Z","caller":"traceutil/trace.go:171","msg":"trace[1961737158] transaction","detail":"{read_only:false; response_revision:8795; number_of_response:1; }","duration":"191.178677ms","start":"2026-10-26T19:28:18.003598Z","end":"2026-10-26T19:28:18.194777Z","steps":["trace[1961737158] 'process raft request' (duration: 159.990974ms)","trace[1961737158] 'compare' (duration: 30.856395ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:28:18.195198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.445383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-10-26T19:28:18.195259Z","caller":"traceutil/trace.go:171","msg":"trace[652357751] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8795; }","duration":"191.614238ms","start":"2026-10-26T19:28:18.003631Z","end":"2026-10-26T19:28:18.195245Z","steps":["trace[652357751] 'agreement among raft nodes before linearized reading' (duration: 191.224868ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:18.222406Z","caller":"traceutil/trace.go:171","msg":"trace[573327371] transaction","detail":"{read_only:false; response_revision:8796; number_of_response:1; }","duration":"215.830152ms","start":"2026-10-26T19:28:18.006553Z","end":"2026-10-26T19:28:18.222383Z","steps":["trace[573327371] 'process raft request' (duration: 215.611096ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:18.222769Z","caller":"traceutil/trace.go:171","msg":"trace[1920759891] transaction","detail":"{read_only:false; response_revision:8797; number_of_response:1; }","duration":"215.775041ms","start":"2026-10-26T19:28:18.006964Z","end":"2026-10-26T19:28:18.222739Z","steps":["trace[1920759891] 'process raft request' (duration: 215.33497ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:28:18.222868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.841041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-sc4pj\" ","response":"range_response_count:1 size:4711"} {"level":"info","ts":"2026-10-26T19:28:18.222964Z","caller":"traceutil/trace.go:171","msg":"trace[1454609836] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-sc4pj; range_end:; response_count:1; response_revision:8798; }","duration":"211.969753ms","start":"2026-10-26T19:28:18.010972Z","end":"2026-10-26T19:28:18.222941Z","steps":["trace[1454609836] 'agreement among raft nodes before linearized reading' (duration: 211.796609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:28:18.223022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.418482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-26T19:28:18.223129Z","caller":"traceutil/trace.go:171","msg":"trace[635626808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8798; }","duration":"111.604076ms","start":"2026-10-26T19:28:18.111501Z","end":"2026-10-26T19:28:18.223105Z","steps":["trace[635626808] 'agreement among raft nodes before linearized reading' (duration: 111.285938ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:18.223145Z","caller":"traceutil/trace.go:171","msg":"trace[422909103] transaction","detail":"{read_only:false; response_revision:8798; number_of_response:1; }","duration":"175.483082ms","start":"2026-10-26T19:28:18.047642Z","end":"2026-10-26T19:28:18.223125Z","steps":["trace[422909103] 'process raft request' (duration: 174.96119ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:28:18.223298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.012182ms","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-10-26T19:28:18.223446Z","caller":"traceutil/trace.go:171","msg":"trace[98659865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8798; }","duration":"113.214307ms","start":"2026-10-26T19:28:18.110203Z","end":"2026-10-26T19:28:18.223417Z","steps":["trace[98659865] 'agreement among raft nodes before linearized reading' (duration: 112.894688ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:18.952362Z","caller":"traceutil/trace.go:171","msg":"trace[1490738716] transaction","detail":"{read_only:false; response_revision:8804; number_of_response:1; }","duration":"319.35118ms","start":"2026-10-26T19:28:18.632943Z","end":"2026-10-26T19:28:18.952295Z","steps":["trace[1490738716] 'process raft request' (duration: 319.036792ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:28:18.952575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:28:18.632912Z","time spent":"319.552405ms","remote":"127.0.0.1:59152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3071,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:29:12.129832Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6537} {"level":"info","ts":"2026-10-26T19:29:12.239953Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6537,"took":"108.145079ms","hash":3544964983,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":26537984,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T19:29:12.24002Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3544964983,"revision":6537,"compact-revision":3188} {"level":"info","ts":"2026-10-26T19:29:30.591235Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a6342599e225e01b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T19:29:30.598274Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-26T19:29:30.598412Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-26T19:29:33.947915Z","caller":"traceutil/trace.go:171","msg":"trace[1257101960] transaction","detail":"{read_only:false; response_revision:9585; number_of_response:1; }","duration":"141.369696ms","start":"2026-10-26T19:29:33.806506Z","end":"2026-10-26T19:29:33.947876Z","steps":["trace[1257101960] 'process raft request' (duration: 86.006428ms)","trace[1257101960] 'compare' (duration: 55.164093ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:29:42.825188Z","caller":"traceutil/trace.go:171","msg":"trace[1433012563] transaction","detail":"{read_only:false; response_revision:9667; number_of_response:1; }","duration":"107.340638ms","start":"2026-10-26T19:29:42.7178Z","end":"2026-10-26T19:29:42.825141Z","steps":["trace[1433012563] 'process raft request' (duration: 94.574988ms)","trace[1433012563] 'compare' (duration: 12.39925ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:29:42.981344Z","caller":"traceutil/trace.go:171","msg":"trace[1275168932] transaction","detail":"{read_only:false; response_revision:9670; number_of_response:1; }","duration":"102.841752ms","start":"2026-10-26T19:29:42.878459Z","end":"2026-10-26T19:29:42.981301Z","steps":["trace[1275168932] 'process raft request' (duration: 96.885768ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:29:43.074029Z","caller":"traceutil/trace.go:171","msg":"trace[1678805975] transaction","detail":"{read_only:false; response_revision:9671; number_of_response:1; }","duration":"151.385324ms","start":"2026-10-26T19:29:42.922612Z","end":"2026-10-26T19:29:43.073998Z","steps":["trace[1678805975] 'process raft request' (duration: 151.20521ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:29:43.188811Z","caller":"traceutil/trace.go:171","msg":"trace[1394393424] transaction","detail":"{read_only:false; number_of_response:0; response_revision:9671; }","duration":"107.142723ms","start":"2026-10-26T19:29:43.081648Z","end":"2026-10-26T19:29:43.188791Z","steps":["trace[1394393424] 'process raft request' (duration: 107.051711ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:29:43.435739Z","caller":"traceutil/trace.go:171","msg":"trace[2059807593] transaction","detail":"{read_only:false; response_revision:9675; number_of_response:1; }","duration":"100.711447ms","start":"2026-10-26T19:29:43.334999Z","end":"2026-10-26T19:29:43.435711Z","steps":["trace[2059807593] 'process raft request' (duration: 100.555223ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:29:43.559695Z","caller":"traceutil/trace.go:171","msg":"trace[154923346] transaction","detail":"{read_only:false; response_revision:9677; number_of_response:1; }","duration":"115.022626ms","start":"2026-10-26T19:29:43.444637Z","end":"2026-10-26T19:29:43.55966Z","steps":["trace[154923346] 'process raft request' (duration: 114.79317ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:31:53.741415Z","caller":"traceutil/trace.go:171","msg":"trace[1398286416] transaction","detail":"{read_only:false; response_revision:10889; number_of_response:1; }","duration":"167.96869ms","start":"2026-10-26T19:31:53.573417Z","end":"2026-10-26T19:31:53.741385Z","steps":["trace[1398286416] 'process raft request' (duration: 167.782597ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:31:53.806906Z","caller":"traceutil/trace.go:171","msg":"trace[123329169] transaction","detail":"{read_only:false; response_revision:10890; number_of_response:1; }","duration":"230.321495ms","start":"2026-10-26T19:31:53.576552Z","end":"2026-10-26T19:31:53.806874Z","steps":["trace[123329169] 'process raft request' (duration: 230.14852ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:32:09.672312Z","caller":"traceutil/trace.go:171","msg":"trace[1374541495] transaction","detail":"{read_only:false; response_revision:11037; number_of_response:1; }","duration":"131.29768ms","start":"2026-10-26T19:32:09.540985Z","end":"2026-10-26T19:32:09.672282Z","steps":["trace[1374541495] 'process raft request' (duration: 131.127166ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:32:09.703896Z","caller":"traceutil/trace.go:171","msg":"trace[470667823] transaction","detail":"{read_only:false; response_revision:11038; number_of_response:1; }","duration":"118.205452ms","start":"2026-10-26T19:32:09.585658Z","end":"2026-10-26T19:32:09.703863Z","steps":["trace[470667823] 'process raft request' (duration: 118.063448ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:15.11017Z","caller":"traceutil/trace.go:171","msg":"trace[1976826153] transaction","detail":"{read_only:false; number_of_response:0; response_revision:11776; }","duration":"122.450332ms","start":"2026-10-26T19:33:14.987694Z","end":"2026-10-26T19:33:15.110144Z","steps":["trace[1976826153] 'process raft request' (duration: 122.350229ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:15.348216Z","caller":"traceutil/trace.go:171","msg":"trace[660156732] linearizableReadLoop","detail":"{readStateIndex:12315; appliedIndex:12313; }","duration":"112.171985ms","start":"2026-10-26T19:33:15.236006Z","end":"2026-10-26T19:33:15.348177Z","steps":["trace[660156732] 'read index received' (duration: 35.947391ms)","trace[660156732] 'applied index is now lower than readState.Index' (duration: 76.223344ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:33:15.34835Z","caller":"traceutil/trace.go:171","msg":"trace[1291197599] transaction","detail":"{read_only:false; response_revision:11779; number_of_response:1; }","duration":"132.142802ms","start":"2026-10-26T19:33:15.21617Z","end":"2026-10-26T19:33:15.348313Z","steps":["trace[1291197599] 'process raft request' (duration: 109.184878ms)","trace[1291197599] 'compare' (duration: 22.662487ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:33:15.348602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.559594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11710"} {"level":"info","ts":"2026-10-26T19:33:15.348683Z","caller":"traceutil/trace.go:171","msg":"trace[72746784] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11779; }","duration":"112.716708ms","start":"2026-10-26T19:33:15.235945Z","end":"2026-10-26T19:33:15.348662Z","steps":["trace[72746784] 'agreement among raft nodes before linearized reading' (duration: 112.42925ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:12.139102Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9403} {"level":"info","ts":"2026-10-26T19:34:12.24212Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9403,"took":"99.461935ms","hash":3169637183,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":28483584,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-26T19:34:12.2422Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3169637183,"revision":9403,"compact-revision":6537} {"level":"info","ts":"2026-10-26T19:39:11.2234Z","caller":"traceutil/trace.go:171","msg":"trace[1029632218] linearizableReadLoop","detail":"{readStateIndex:15374; appliedIndex:15373; }","duration":"128.153537ms","start":"2026-10-26T19:39:11.095203Z","end":"2026-10-26T19:39:11.223357Z","steps":["trace[1029632218] 'read index received' (duration: 126.363812ms)","trace[1029632218] 'applied index is now lower than readState.Index' (duration: 1.788545ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:39:11.223957Z","caller":"traceutil/trace.go:171","msg":"trace[776526568] transaction","detail":"{read_only:false; response_revision:14684; number_of_response:1; }","duration":"173.554706ms","start":"2026-10-26T19:39:11.05037Z","end":"2026-10-26T19:39:11.223925Z","steps":["trace[776526568] 'process raft request' (duration: 171.246547ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:39:11.224207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.949968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11710"} {"level":"info","ts":"2026-10-26T19:39:11.224325Z","caller":"traceutil/trace.go:171","msg":"trace[1301215622] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14684; }","duration":"129.121143ms","start":"2026-10-26T19:39:11.095177Z","end":"2026-10-26T19:39:11.224298Z","steps":["trace[1301215622] 'agreement among raft nodes before linearized reading' (duration: 128.752093ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:39:12.146574Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12268} {"level":"info","ts":"2026-10-26T19:39:12.253045Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12268,"took":"102.424225ms","hash":1445386748,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":25681920,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T19:39:12.253131Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1445386748,"revision":12268,"compact-revision":9403} {"level":"info","ts":"2026-10-26T19:39:36.259783Z","caller":"traceutil/trace.go:171","msg":"trace[681446251] transaction","detail":"{read_only:false; response_revision:14856; number_of_response:1; }","duration":"103.047752ms","start":"2026-10-26T19:39:36.156704Z","end":"2026-10-26T19:39:36.259751Z","steps":["trace[681446251] 'process raft request' (duration: 102.364964ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:39:54.296045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.168228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:39:54.296501Z","caller":"traceutil/trace.go:171","msg":"trace[1767010314] transaction","detail":"{read_only:false; response_revision:14992; number_of_response:1; }","duration":"668.702777ms","start":"2026-10-26T19:39:53.627767Z","end":"2026-10-26T19:39:54.29647Z","steps":["trace[1767010314] 'process raft request' (duration: 560.962286ms)","trace[1767010314] 'compare' (duration: 106.915651ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:39:54.296586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:39:53.62772Z","time spent":"668.829872ms","remote":"127.0.0.1:59112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12713,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:39:54.380089Z","caller":"traceutil/trace.go:171","msg":"trace[1186281188] linearizableReadLoop","detail":"{readStateIndex:15702; appliedIndex:15700; }","duration":"529.789093ms","start":"2026-10-26T19:39:53.850269Z","end":"2026-10-26T19:39:54.380058Z","steps":["trace[1186281188] 'read index received' (duration: 338.619584ms)","trace[1186281188] 'applied index is now lower than readState.Index' (duration: 191.168059ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:39:54.380137Z","caller":"traceutil/trace.go:171","msg":"trace[1290289561] transaction","detail":"{read_only:false; response_revision:14993; number_of_response:1; }","duration":"661.889432ms","start":"2026-10-26T19:39:53.718221Z","end":"2026-10-26T19:39:54.38011Z","steps":["trace[1290289561] 'process raft request' (duration: 661.625906ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:39:54.380177Z","caller":"traceutil/trace.go:171","msg":"trace[1445646065] transaction","detail":"{read_only:false; response_revision:14994; number_of_response:1; }","duration":"452.897184ms","start":"2026-10-26T19:39:53.927249Z","end":"2026-10-26T19:39:54.380146Z","steps":["trace[1445646065] 'process raft request' (duration: 452.755162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:39:54.380282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:39:53.927224Z","time spent":"453.002917ms","remote":"127.0.0.1:59188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:39:54.380383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.797221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5ttzb\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-10-26T19:39:54.3803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:39:53.718198Z","time spent":"661.993465ms","remote":"127.0.0.1:59188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:39:54.380429Z","caller":"traceutil/trace.go:171","msg":"trace[862321426] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5ttzb; range_end:; response_count:1; response_revision:14994; }","duration":"206.893293ms","start":"2026-10-26T19:39:54.173522Z","end":"2026-10-26T19:39:54.380415Z","steps":["trace[862321426] 'agreement among raft nodes before linearized reading' (duration: 206.697728ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:39:54.380394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.097471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:39:54.380522Z","caller":"traceutil/trace.go:171","msg":"trace[1190100665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14994; }","duration":"530.267284ms","start":"2026-10-26T19:39:53.850221Z","end":"2026-10-26T19:39:54.380489Z","steps":["trace[1190100665] 'agreement among raft nodes before linearized reading' (duration: 530.004698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:39:54.38057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:39:53.850202Z","time spent":"530.359987ms","remote":"127.0.0.1:58896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T19:39:54.566065Z","caller":"traceutil/trace.go:171","msg":"trace[397129558] linearizableReadLoop","detail":"{readStateIndex:15704; appliedIndex:15703; }","duration":"136.925143ms","start":"2026-10-26T19:39:54.429094Z","end":"2026-10-26T19:39:54.566019Z","steps":["trace[397129558] 'read index received' (duration: 111.15844ms)","trace[397129558] 'applied index is now lower than readState.Index' (duration: 25.765413ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:39:54.566281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.133868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:39:54.566331Z","caller":"traceutil/trace.go:171","msg":"trace[1256659726] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:14995; }","duration":"137.246322ms","start":"2026-10-26T19:39:54.429068Z","end":"2026-10-26T19:39:54.566314Z","steps":["trace[1256659726] 'agreement among raft nodes before linearized reading' (duration: 137.092178ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:39:54.566427Z","caller":"traceutil/trace.go:171","msg":"trace[1357744910] transaction","detail":"{read_only:false; response_revision:14995; number_of_response:1; }","duration":"166.899374ms","start":"2026-10-26T19:39:54.399482Z","end":"2026-10-26T19:39:54.566381Z","steps":["trace[1357744910] 'process raft request' (duration: 140.833663ms)","trace[1357744910] 'compare' (duration: 25.533318ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:41:10.059247Z","caller":"traceutil/trace.go:171","msg":"trace[129932229] transaction","detail":"{read_only:false; response_revision:16159; number_of_response:1; }","duration":"105.067904ms","start":"2026-10-26T19:41:09.954148Z","end":"2026-10-26T19:41:10.059216Z","steps":["trace[129932229] 'process raft request' (duration: 104.88955ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:44.537801Z","caller":"traceutil/trace.go:171","msg":"trace[992752306] linearizableReadLoop","detail":"{readStateIndex:17159; appliedIndex:17157; }","duration":"178.715259ms","start":"2026-10-26T19:41:44.359039Z","end":"2026-10-26T19:41:44.537754Z","steps":["trace[992752306] 'read index received' (duration: 54.071162ms)","trace[992752306] 'applied index is now lower than readState.Index' (duration: 124.642217ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:41:44.538075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.009316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T19:41:44.538129Z","caller":"traceutil/trace.go:171","msg":"trace[1888798463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16389; }","duration":"179.086768ms","start":"2026-10-26T19:41:44.359023Z","end":"2026-10-26T19:41:44.53811Z","steps":["trace[1888798463] 'agreement among raft nodes before linearized reading' (duration: 178.927844ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:44.538172Z","caller":"traceutil/trace.go:171","msg":"trace[58469187] transaction","detail":"{read_only:false; response_revision:16389; number_of_response:1; }","duration":"199.081833ms","start":"2026-10-26T19:41:44.339047Z","end":"2026-10-26T19:41:44.538128Z","steps":["trace[58469187] 'process raft request' (duration: 149.435296ms)","trace[58469187] 'compare' (duration: 48.814576ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:41:45.280563Z","caller":"traceutil/trace.go:171","msg":"trace[1448663578] linearizableReadLoop","detail":"{readStateIndex:17164; appliedIndex:17163; }","duration":"100.145087ms","start":"2026-10-26T19:41:45.180384Z","end":"2026-10-26T19:41:45.280529Z","steps":["trace[1448663578] 'read index received' (duration: 99.952622ms)","trace[1448663578] 'applied index is now lower than readState.Index' (duration: 191.225µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:41:45.280585Z","caller":"traceutil/trace.go:171","msg":"trace[215669845] transaction","detail":"{read_only:false; response_revision:16394; number_of_response:1; }","duration":"102.012636ms","start":"2026-10-26T19:41:45.178533Z","end":"2026-10-26T19:41:45.280545Z","steps":["trace[215669845] 'process raft request' (duration: 101.834211ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:41:45.280877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.474705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-10-26T19:41:45.280974Z","caller":"traceutil/trace.go:171","msg":"trace[1040608346] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16394; }","duration":"100.605349ms","start":"2026-10-26T19:41:45.180346Z","end":"2026-10-26T19:41:45.280952Z","steps":["trace[1040608346] 'agreement among raft nodes before linearized reading' (duration: 100.308231ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:45.539857Z","caller":"traceutil/trace.go:171","msg":"trace[1107607512] linearizableReadLoop","detail":"{readStateIndex:17166; appliedIndex:17165; }","duration":"101.601444ms","start":"2026-10-26T19:41:45.438221Z","end":"2026-10-26T19:41:45.539823Z","steps":["trace[1107607512] 'read index received' (duration: 35.390281ms)","trace[1107607512] 'applied index is now lower than readState.Index' (duration: 66.209943ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:41:45.540132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.892061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.148\" ","response":"range_response_count:1 size:139"} {"level":"info","ts":"2026-10-26T19:41:45.540177Z","caller":"traceutil/trace.go:171","msg":"trace[305335708] range","detail":"{range_begin:/registry/masterleases/162.253.55.148; range_end:; response_count:1; response_revision:16396; }","duration":"102.021575ms","start":"2026-10-26T19:41:45.438142Z","end":"2026-10-26T19:41:45.540164Z","steps":["trace[305335708] 'agreement among raft nodes before linearized reading' (duration: 101.796709ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:45.540492Z","caller":"traceutil/trace.go:171","msg":"trace[802194496] transaction","detail":"{read_only:false; response_revision:16396; number_of_response:1; }","duration":"254.822407ms","start":"2026-10-26T19:41:45.285655Z","end":"2026-10-26T19:41:45.540477Z","steps":["trace[802194496] 'process raft request' (duration: 187.974437ms)","trace[802194496] 'compare' (duration: 66.051909ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:44:12.153475Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14691} {"level":"info","ts":"2026-10-26T19:44:12.253538Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14691,"took":"96.55087ms","hash":3970333776,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":29941760,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T19:44:12.253625Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3970333776,"revision":14691,"compact-revision":12268} {"level":"info","ts":"2026-10-26T19:46:53.320062Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a6342599e225e01b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T19:46:53.326749Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-26T19:46:53.326876Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-10-26T19:47:19.442708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.901653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"warn","ts":"2026-10-26T19:47:19.44285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.98304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-10-26T19:47:19.443083Z","caller":"traceutil/trace.go:171","msg":"trace[1055847993] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:19369; }","duration":"247.310894ms","start":"2026-10-26T19:47:19.195742Z","end":"2026-10-26T19:47:19.443053Z","steps":["trace[1055847993] 'range keys from in-memory index tree' (duration: 246.694549ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:47:19.442854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.965395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-4sf6q\" ","response":"range_response_count:1 size:20878"} {"level":"info","ts":"2026-10-26T19:47:19.443225Z","caller":"traceutil/trace.go:171","msg":"trace[2095671501] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-4sf6q; range_end:; response_count:1; response_revision:19369; }","duration":"296.372576ms","start":"2026-10-26T19:47:19.146816Z","end":"2026-10-26T19:47:19.443188Z","steps":["trace[2095671501] 'range keys from in-memory index tree' (duration: 295.79457ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:47:19.442955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.219339ms","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-10-26T19:47:19.443428Z","caller":"traceutil/trace.go:171","msg":"trace[593984176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19369; }","duration":"264.718032ms","start":"2026-10-26T19:47:19.178695Z","end":"2026-10-26T19:47:19.443413Z","steps":["trace[593984176] 'range keys from in-memory index tree' (duration: 264.000533ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:47:19.442963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.495231ms","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-10-26T19:47:19.443626Z","caller":"traceutil/trace.go:171","msg":"trace[1512457447] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19369; }","duration":"271.151248ms","start":"2026-10-26T19:47:19.172458Z","end":"2026-10-26T19:47:19.443609Z","steps":["trace[1512457447] 'range keys from in-memory index tree' (duration: 270.344417ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:47:19.443197Z","caller":"traceutil/trace.go:171","msg":"trace[176800257] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19369; }","duration":"253.288698ms","start":"2026-10-26T19:47:19.189822Z","end":"2026-10-26T19:47:19.443111Z","steps":["trace[176800257] 'range keys from in-memory index tree' (duration: 252.802675ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:47:19.840947Z","caller":"traceutil/trace.go:171","msg":"trace[202579624] transaction","detail":"{read_only:false; response_revision:19373; number_of_response:1; }","duration":"375.299074ms","start":"2026-10-26T19:47:19.465614Z","end":"2026-10-26T19:47:19.840913Z","steps":["trace[202579624] 'process raft request' (duration: 375.037307ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:47:19.841159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:47:19.4656Z","time spent":"375.443387ms","remote":"127.0.0.1:59112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20996,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:47:19.841147Z","caller":"traceutil/trace.go:171","msg":"trace[455396843] linearizableReadLoop","detail":"{readStateIndex:20293; appliedIndex:20291; }","duration":"326.411077ms","start":"2026-10-26T19:47:19.514702Z","end":"2026-10-26T19:47:19.841113Z","steps":["trace[455396843] 'read index received' (duration: 325.819032ms)","trace[455396843] 'applied index is now lower than readState.Index' (duration: 590.685µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:47:19.841738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.080534ms","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-10-26T19:47:19.841794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.904858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:569"} {"level":"info","ts":"2026-10-26T19:47:19.841821Z","caller":"traceutil/trace.go:171","msg":"trace[45026402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19374; }","duration":"376.227798ms","start":"2026-10-26T19:47:19.465581Z","end":"2026-10-26T19:47:19.841808Z","steps":["trace[45026402] 'agreement among raft nodes before linearized reading' (duration: 375.92762ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:47:19.841844Z","caller":"traceutil/trace.go:171","msg":"trace[1822963373] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:19374; }","duration":"323.00027ms","start":"2026-10-26T19:47:19.518825Z","end":"2026-10-26T19:47:19.841825Z","steps":["trace[1822963373] 'agreement among raft nodes before linearized reading' (duration: 322.771364ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:47:19.841858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:47:19.465571Z","time spent":"376.277229ms","remote":"127.0.0.1:59188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T19:47:19.841892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:47:19.518803Z","time spent":"323.078862ms","remote":"127.0.0.1:59188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-10-26T19:47:19.842126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.501822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T19:47:19.842163Z","caller":"traceutil/trace.go:171","msg":"trace[24106524] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:19374; }","duration":"221.713597ms","start":"2026-10-26T19:47:19.62044Z","end":"2026-10-26T19:47:19.842154Z","steps":["trace[24106524] 'agreement among raft nodes before linearized reading' (duration: 221.635725ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:47:19.842195Z","caller":"traceutil/trace.go:171","msg":"trace[1983436114] transaction","detail":"{read_only:false; response_revision:19374; number_of_response:1; }","duration":"370.717277ms","start":"2026-10-26T19:47:19.471466Z","end":"2026-10-26T19:47:19.842183Z","steps":["trace[1983436114] 'process raft request' (duration: 369.382802ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:47:19.842274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:47:19.471452Z","time spent":"370.783218ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:49:12.16391Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17680} {"level":"info","ts":"2026-10-26T19:49:12.283046Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17680,"took":"114.662036ms","hash":1737428111,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":31215616,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T19:49:12.28313Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1737428111,"revision":17680,"compact-revision":14691} {"level":"info","ts":"2026-10-26T19:54:12.174407Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20562} {"level":"info","ts":"2026-10-26T19:54:12.309257Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20562,"took":"128.861029ms","hash":1153902978,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":32751616,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T19:54:12.30934Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1153902978,"revision":20562,"compact-revision":17680} {"level":"info","ts":"2026-10-26T19:54:18.473099Z","caller":"traceutil/trace.go:171","msg":"trace[2117578391] linearizableReadLoop","detail":"{readStateIndex:24532; appliedIndex:24531; }","duration":"113.701511ms","start":"2026-10-26T19:54:18.359372Z","end":"2026-10-26T19:54:18.473073Z","steps":["trace[2117578391] 'read index received' (duration: 106.540336ms)","trace[2117578391] 'applied index is now lower than readState.Index' (duration: 7.160055ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:54:18.473161Z","caller":"traceutil/trace.go:171","msg":"trace[517146043] transaction","detail":"{read_only:false; response_revision:23422; number_of_response:1; }","duration":"145.515886ms","start":"2026-10-26T19:54:18.32761Z","end":"2026-10-26T19:54:18.473125Z","steps":["trace[517146043] 'process raft request' (duration: 138.29281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:54:18.473233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.833664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T19:54:18.473268Z","caller":"traceutil/trace.go:171","msg":"trace[734926217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23422; }","duration":"113.911466ms","start":"2026-10-26T19:54:18.359346Z","end":"2026-10-26T19:54:18.473258Z","steps":["trace[734926217] 'agreement among raft nodes before linearized reading' (duration: 113.834123ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:54:23.914572Z","caller":"traceutil/trace.go:171","msg":"trace[2080859323] transaction","detail":"{read_only:false; response_revision:23452; number_of_response:1; }","duration":"115.833343ms","start":"2026-10-26T19:54:23.79871Z","end":"2026-10-26T19:54:23.914544Z","steps":["trace[2080859323] 'process raft request' (duration: 115.655009ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:54:50.493914Z","caller":"traceutil/trace.go:171","msg":"trace[1220814947] transaction","detail":"{read_only:false; response_revision:23622; number_of_response:1; }","duration":"167.751247ms","start":"2026-10-26T19:54:50.326124Z","end":"2026-10-26T19:54:50.493875Z","steps":["trace[1220814947] 'process raft request' (duration: 167.534331ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:54:50.504937Z","caller":"traceutil/trace.go:171","msg":"trace[486597511] transaction","detail":"{read_only:false; response_revision:23624; number_of_response:1; }","duration":"173.771052ms","start":"2026-10-26T19:54:50.331119Z","end":"2026-10-26T19:54:50.50489Z","steps":["trace[486597511] 'process raft request' (duration: 173.71142ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:54:50.504986Z","caller":"traceutil/trace.go:171","msg":"trace[222724007] transaction","detail":"{read_only:false; response_revision:23623; number_of_response:1; }","duration":"174.977902ms","start":"2026-10-26T19:54:50.32997Z","end":"2026-10-26T19:54:50.504948Z","steps":["trace[222724007] 'process raft request' (duration: 174.663454ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:54:50.843761Z","caller":"traceutil/trace.go:171","msg":"trace[1458022843] transaction","detail":"{read_only:false; response_revision:23625; number_of_response:1; }","duration":"316.737973ms","start":"2026-10-26T19:54:50.526997Z","end":"2026-10-26T19:54:50.843734Z","steps":["trace[1458022843] 'process raft request' (duration: 232.532101ms)","trace[1458022843] 'compare' (duration: 84.042918ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:54:50.84392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:54:50.52697Z","time spent":"316.852186ms","remote":"127.0.0.1:59188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":536,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:54:50.854794Z","caller":"traceutil/trace.go:171","msg":"trace[314700785] linearizableReadLoop","detail":"{readStateIndex:24748; appliedIndex:24746; }","duration":"197.647295ms","start":"2026-10-26T19:54:50.65711Z","end":"2026-10-26T19:54:50.854758Z","steps":["trace[314700785] 'read index received' (duration: 102.512412ms)","trace[314700785] 'applied index is now lower than readState.Index' (duration: 95.133223ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:54:50.854872Z","caller":"traceutil/trace.go:171","msg":"trace[570266331] transaction","detail":"{read_only:false; response_revision:23626; number_of_response:1; }","duration":"325.901228ms","start":"2026-10-26T19:54:50.528923Z","end":"2026-10-26T19:54:50.854824Z","steps":["trace[570266331] 'process raft request' (duration: 325.60078ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:54:50.855053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:54:50.528894Z","time spent":"326.071092ms","remote":"127.0.0.1:59188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":551,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:54:50.855072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.943012ms","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-10-26T19:54:50.855113Z","caller":"traceutil/trace.go:171","msg":"trace[1895635159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23626; }","duration":"198.051115ms","start":"2026-10-26T19:54:50.65705Z","end":"2026-10-26T19:54:50.855101Z","steps":["trace[1895635159] 'agreement among raft nodes before linearized reading' (duration: 197.83936ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:54:51.085199Z","caller":"traceutil/trace.go:171","msg":"trace[9794805] transaction","detail":"{read_only:false; response_revision:23627; number_of_response:1; }","duration":"225.790019ms","start":"2026-10-26T19:54:50.859384Z","end":"2026-10-26T19:54:51.085174Z","steps":["trace[9794805] 'process raft request' (duration: 225.588653ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:54:51.203194Z","caller":"traceutil/trace.go:171","msg":"trace[1683448063] linearizableReadLoop","detail":"{readStateIndex:24751; appliedIndex:24748; }","duration":"201.121244ms","start":"2026-10-26T19:54:51.00203Z","end":"2026-10-26T19:54:51.203151Z","steps":["trace[1683448063] 'read index received' (duration: 82.924419ms)","trace[1683448063] 'applied index is now lower than readState.Index' (duration: 118.194135ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:54:51.203238Z","caller":"traceutil/trace.go:171","msg":"trace[1518913921] transaction","detail":"{read_only:false; response_revision:23628; number_of_response:1; }","duration":"214.271672ms","start":"2026-10-26T19:54:50.988932Z","end":"2026-10-26T19:54:51.203203Z","steps":["trace[1518913921] 'process raft request' (duration: 213.990585ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:54:51.306001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.348128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-26T19:54:51.306107Z","caller":"traceutil/trace.go:171","msg":"trace[1845406508] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:23628; }","duration":"190.520592ms","start":"2026-10-26T19:54:51.115562Z","end":"2026-10-26T19:54:51.306082Z","steps":["trace[1845406508] 'agreement among raft nodes before linearized reading' (duration: 87.820135ms)","trace[1845406508] 'range keys from in-memory index tree' (duration: 102.507511ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:54:51.30638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.330134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-10-26T19:54:51.306451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.822688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-26T19:54:51.306525Z","caller":"traceutil/trace.go:171","msg":"trace[851190038] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23628; }","duration":"304.510369ms","start":"2026-10-26T19:54:51.001963Z","end":"2026-10-26T19:54:51.306474Z","steps":["trace[851190038] 'agreement among raft nodes before linearized reading' (duration: 201.378511ms)","trace[851190038] 'range keys from in-memory index tree' (duration: 102.886491ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:54:51.306546Z","caller":"traceutil/trace.go:171","msg":"trace[1313900609] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:23628; }","duration":"216.973051ms","start":"2026-10-26T19:54:51.08955Z","end":"2026-10-26T19:54:51.306523Z","steps":["trace[1313900609] 'agreement among raft nodes before linearized reading' (duration: 113.868454ms)","trace[1313900609] 'range keys from in-memory index tree' (duration: 102.896602ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:54:51.306594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:54:51.001939Z","time spent":"304.640502ms","remote":"127.0.0.1:59188","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-10-26T19:54:51.306391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.845504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-26T19:54:51.306698Z","caller":"traceutil/trace.go:171","msg":"trace[541832829] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23628; }","duration":"225.234354ms","start":"2026-10-26T19:54:51.081429Z","end":"2026-10-26T19:54:51.306663Z","steps":["trace[541832829] 'agreement among raft nodes before linearized reading' (duration: 121.959962ms)","trace[541832829] 'range keys from in-memory index tree' (duration: 102.797029ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:54:51.582314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.912351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11711"} {"level":"info","ts":"2026-10-26T19:54:51.582424Z","caller":"traceutil/trace.go:171","msg":"trace[699684690] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23631; }","duration":"101.098156ms","start":"2026-10-26T19:54:51.481292Z","end":"2026-10-26T19:54:51.58239Z","steps":["trace[699684690] 'range keys from in-memory index tree' (duration: 100.638264ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:58:31.311485Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006ae5.wal"} {"level":"info","ts":"2026-10-26T19:58:40.845058Z","caller":"traceutil/trace.go:171","msg":"trace[666397773] transaction","detail":"{read_only:false; response_revision:26382; number_of_response:1; }","duration":"190.275605ms","start":"2026-10-26T19:58:40.654733Z","end":"2026-10-26T19:58:40.845008Z","steps":["trace[666397773] 'process raft request' (duration: 185.8052ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:58:40.861817Z","caller":"traceutil/trace.go:171","msg":"trace[919390632] linearizableReadLoop","detail":"{readStateIndex:27622; appliedIndex:27619; }","duration":"115.515746ms","start":"2026-10-26T19:58:40.746276Z","end":"2026-10-26T19:58:40.861792Z","steps":["trace[919390632] 'read index received' (duration: 94.240069ms)","trace[919390632] 'applied index is now lower than readState.Index' (duration: 21.273807ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:58:40.861829Z","caller":"traceutil/trace.go:171","msg":"trace[787073341] transaction","detail":"{read_only:false; response_revision:26383; number_of_response:1; }","duration":"147.748944ms","start":"2026-10-26T19:58:40.714014Z","end":"2026-10-26T19:58:40.861763Z","steps":["trace[787073341] 'process raft request' (duration: 147.556429ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:58:40.862232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.926856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T19:58:40.862631Z","caller":"traceutil/trace.go:171","msg":"trace[41841718] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:26383; }","duration":"116.408449ms","start":"2026-10-26T19:58:40.746205Z","end":"2026-10-26T19:58:40.862614Z","steps":["trace[41841718] 'agreement among raft nodes before linearized reading' (duration: 115.865035ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:59:12.181873Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23379} {"level":"info","ts":"2026-10-26T19:59:12.308799Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23379,"took":"121.485809ms","hash":1733563271,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":44728320,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-26T19:59:12.308893Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1733563271,"revision":23379,"compact-revision":20562} {"level":"info","ts":"2026-10-26T20:00:03.625689Z","caller":"traceutil/trace.go:171","msg":"trace[1547414007] transaction","detail":"{read_only:false; response_revision:27446; number_of_response:1; }","duration":"184.520237ms","start":"2026-10-26T20:00:03.441138Z","end":"2026-10-26T20:00:03.625658Z","steps":["trace[1547414007] 'process raft request' (duration: 184.318873ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:00:03.634586Z","caller":"traceutil/trace.go:171","msg":"trace[1090675214] linearizableReadLoop","detail":"{readStateIndex:28722; appliedIndex:28720; }","duration":"119.95474ms","start":"2026-10-26T20:00:03.514611Z","end":"2026-10-26T20:00:03.634566Z","steps":["trace[1090675214] 'read index received' (duration: 110.845077ms)","trace[1090675214] 'applied index is now lower than readState.Index' (duration: 9.107933ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:00:03.634737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.097584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-26T20:00:03.634781Z","caller":"traceutil/trace.go:171","msg":"trace[502297819] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:27447; }","duration":"120.163476ms","start":"2026-10-26T20:00:03.514607Z","end":"2026-10-26T20:00:03.634771Z","steps":["trace[502297819] 'agreement among raft nodes before linearized reading' (duration: 120.031452ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:00:03.635089Z","caller":"traceutil/trace.go:171","msg":"trace[382926924] transaction","detail":"{read_only:false; response_revision:27447; number_of_response:1; }","duration":"157.595757ms","start":"2026-10-26T20:00:03.477482Z","end":"2026-10-26T20:00:03.635078Z","steps":["trace[382926924] 'process raft request' (duration: 157.006911ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:01:53.045731Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a6342599e225e01b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:01:53.063004Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-26T20:01:53.063124Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-26T20:02:03.794489Z","caller":"traceutil/trace.go:171","msg":"trace[1819571028] linearizableReadLoop","detail":"{readStateIndex:30111; appliedIndex:30110; }","duration":"119.469007ms","start":"2026-10-26T20:02:03.674992Z","end":"2026-10-26T20:02:03.794461Z","steps":["trace[1819571028] 'read index received' (duration: 119.055036ms)","trace[1819571028] 'applied index is now lower than readState.Index' (duration: 412.461µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:02:03.794578Z","caller":"traceutil/trace.go:171","msg":"trace[1415779602] transaction","detail":"{read_only:false; response_revision:28784; number_of_response:1; }","duration":"161.808364ms","start":"2026-10-26T20:02:03.632724Z","end":"2026-10-26T20:02:03.794533Z","steps":["trace[1415779602] 'process raft request' (duration: 161.361842ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:03.794744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.738184ms","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-10-26T20:02:03.794789Z","caller":"traceutil/trace.go:171","msg":"trace[630726577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28784; }","duration":"119.847456ms","start":"2026-10-26T20:02:03.674931Z","end":"2026-10-26T20:02:03.794778Z","steps":["trace[630726577] 'agreement among raft nodes before linearized reading' (duration: 119.658961ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:02:03.961361Z","caller":"traceutil/trace.go:171","msg":"trace[156333146] transaction","detail":"{read_only:false; response_revision:28785; number_of_response:1; }","duration":"200.416447ms","start":"2026-10-26T20:02:03.760919Z","end":"2026-10-26T20:02:03.961336Z","steps":["trace[156333146] 'process raft request' (duration: 199.660237ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:02:03.961388Z","caller":"traceutil/trace.go:171","msg":"trace[556758634] transaction","detail":"{read_only:false; response_revision:28786; number_of_response:1; }","duration":"199.570065ms","start":"2026-10-26T20:02:03.761783Z","end":"2026-10-26T20:02:03.961353Z","steps":["trace[556758634] 'process raft request' (duration: 199.35446ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:02:03.96153Z","caller":"traceutil/trace.go:171","msg":"trace[1407192433] linearizableReadLoop","detail":"{readStateIndex:30113; appliedIndex:30111; }","duration":"166.907196ms","start":"2026-10-26T20:02:03.794564Z","end":"2026-10-26T20:02:03.961472Z","steps":["trace[1407192433] 'read index received' (duration: 166.041374ms)","trace[1407192433] 'applied index is now lower than readState.Index' (duration: 862.712µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:02:03.96177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.916835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:02:03.961894Z","caller":"traceutil/trace.go:171","msg":"trace[1690021164] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:28786; }","duration":"197.12011ms","start":"2026-10-26T20:02:03.764748Z","end":"2026-10-26T20:02:03.961868Z","steps":["trace[1690021164] 'agreement among raft nodes before linearized reading' (duration: 196.833644ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:02:03.974465Z","caller":"traceutil/trace.go:171","msg":"trace[1076254013] transaction","detail":"{read_only:false; response_revision:28787; number_of_response:1; }","duration":"174.092511ms","start":"2026-10-26T20:02:03.800344Z","end":"2026-10-26T20:02:03.974436Z","steps":["trace[1076254013] 'process raft request' (duration: 173.802993ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:03.974469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.932663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:02:03.974687Z","caller":"traceutil/trace.go:171","msg":"trace[2104009137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28788; }","duration":"124.188229ms","start":"2026-10-26T20:02:03.850473Z","end":"2026-10-26T20:02:03.974661Z","steps":["trace[2104009137] 'agreement among raft nodes before linearized reading' (duration: 123.910682ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:02:03.974507Z","caller":"traceutil/trace.go:171","msg":"trace[1368426198] transaction","detail":"{read_only:false; response_revision:28788; number_of_response:1; }","duration":"106.972397ms","start":"2026-10-26T20:02:03.867507Z","end":"2026-10-26T20:02:03.974479Z","steps":["trace[1368426198] 'process raft request' (duration: 106.808432ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:04.466885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.302257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-26T20:02:04.466951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.459893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-26T20:02:04.466977Z","caller":"traceutil/trace.go:171","msg":"trace[950038774] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28788; }","duration":"391.449541ms","start":"2026-10-26T20:02:04.075505Z","end":"2026-10-26T20:02:04.466954Z","steps":["trace[950038774] 'range keys from in-memory index tree' (duration: 391.109492ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:04.467061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:04.075492Z","time spent":"391.558144ms","remote":"127.0.0.1:59188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-26T20:02:04.467043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.808203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-26T20:02:04.467063Z","caller":"traceutil/trace.go:171","msg":"trace[801290668] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28788; }","duration":"379.632427ms","start":"2026-10-26T20:02:04.087405Z","end":"2026-10-26T20:02:04.467038Z","steps":["trace[801290668] 'range keys from in-memory index tree' (duration: 379.235987ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:02:04.467117Z","caller":"traceutil/trace.go:171","msg":"trace[1940343162] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28788; }","duration":"337.938337ms","start":"2026-10-26T20:02:04.129159Z","end":"2026-10-26T20:02:04.467097Z","steps":["trace[1940343162] 'range keys from in-memory index tree' (duration: 337.610058ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:04.467123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:04.087376Z","time spent":"379.73614ms","remote":"127.0.0.1:59188","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-10-26T20:02:04.467151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:04.12913Z","time spent":"338.014569ms","remote":"127.0.0.1:59188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":550,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-10-26T20:02:04.467138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.109911ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:02:04.467246Z","caller":"traceutil/trace.go:171","msg":"trace[1019068438] linearizableReadLoop","detail":"{readStateIndex:30116; appliedIndex:30115; }","duration":"308.706697ms","start":"2026-10-26T20:02:04.158522Z","end":"2026-10-26T20:02:04.467229Z","steps":["trace[1019068438] 'read index received' (duration: 155.37509ms)","trace[1019068438] 'applied index is now lower than readState.Index' (duration: 153.329967ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:02:04.467276Z","caller":"traceutil/trace.go:171","msg":"trace[1957570910] transaction","detail":"{read_only:false; response_revision:28789; number_of_response:1; }","duration":"314.962908ms","start":"2026-10-26T20:02:04.152291Z","end":"2026-10-26T20:02:04.467254Z","steps":["trace[1957570910] 'process raft request' (duration: 161.644661ms)","trace[1957570910] 'compare' (duration: 152.838814ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:02:04.467372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.85342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T20:02:04.467396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:04.152257Z","time spent":"315.04823ms","remote":"127.0.0.1:59188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:02:04.467413Z","caller":"traceutil/trace.go:171","msg":"trace[1258748423] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:28789; }","duration":"308.933832ms","start":"2026-10-26T20:02:04.158467Z","end":"2026-10-26T20:02:04.467401Z","steps":["trace[1258748423] 'agreement among raft nodes before linearized reading' (duration: 308.86132ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:04.467446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:02:04.158449Z","time spent":"308.987684ms","remote":"127.0.0.1:58836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"warn","ts":"2026-10-26T20:02:04.467382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.04445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:02:04.467563Z","caller":"traceutil/trace.go:171","msg":"trace[27989535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28789; }","duration":"109.277125ms","start":"2026-10-26T20:02:04.358279Z","end":"2026-10-26T20:02:04.467556Z","steps":["trace[27989535] 'agreement among raft nodes before linearized reading' (duration: 109.0764ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:02:04.661269Z","caller":"traceutil/trace.go:171","msg":"trace[2115499801] transaction","detail":"{read_only:false; response_revision:28790; number_of_response:1; }","duration":"187.637188ms","start":"2026-10-26T20:02:04.473601Z","end":"2026-10-26T20:02:04.661238Z","steps":["trace[2115499801] 'process raft request' (duration: 187.441433ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:02:04.664659Z","caller":"traceutil/trace.go:171","msg":"trace[1044306753] transaction","detail":"{read_only:false; response_revision:28792; number_of_response:1; }","duration":"190.906421ms","start":"2026-10-26T20:02:04.473717Z","end":"2026-10-26T20:02:04.664623Z","steps":["trace[1044306753] 'process raft request' (duration: 190.844249ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:02:04.664814Z","caller":"traceutil/trace.go:171","msg":"trace[206449789] transaction","detail":"{read_only:false; response_revision:28791; number_of_response:1; }","duration":"191.065125ms","start":"2026-10-26T20:02:04.473692Z","end":"2026-10-26T20:02:04.664757Z","steps":["trace[206449789] 'process raft request' (duration: 190.668055ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:02:59.681752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.669084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-10-26T20:02:59.681959Z","caller":"traceutil/trace.go:171","msg":"trace[76152376] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:29377; }","duration":"114.938532ms","start":"2026-10-26T20:02:59.566997Z","end":"2026-10-26T20:02:59.681936Z","steps":["trace[76152376] 'range keys from in-memory index tree' (duration: 113.982078ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:03:05.210395Z","caller":"traceutil/trace.go:171","msg":"trace[1212815764] linearizableReadLoop","detail":"{readStateIndex:30775; appliedIndex:30774; }","duration":"171.184024ms","start":"2026-10-26T20:03:05.039178Z","end":"2026-10-26T20:03:05.210362Z","steps":["trace[1212815764] 'read index received' (duration: 170.96804ms)","trace[1212815764] 'applied index is now lower than readState.Index' (duration: 214.864µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:03:05.210485Z","caller":"traceutil/trace.go:171","msg":"trace[922845325] transaction","detail":"{read_only:false; response_revision:29423; number_of_response:1; }","duration":"246.16514ms","start":"2026-10-26T20:03:04.964263Z","end":"2026-10-26T20:03:05.210428Z","steps":["trace[922845325] 'process raft request' (duration: 245.916955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:03:05.210682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.920046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-26T20:03:05.210731Z","caller":"traceutil/trace.go:171","msg":"trace[399509703] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29423; }","duration":"160.015518ms","start":"2026-10-26T20:03:05.050701Z","end":"2026-10-26T20:03:05.210716Z","steps":["trace[399509703] 'agreement among raft nodes before linearized reading' (duration: 159.837563ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:03:05.2107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.494813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-26T20:03:05.210886Z","caller":"traceutil/trace.go:171","msg":"trace[1982281340] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:29423; }","duration":"171.78547ms","start":"2026-10-26T20:03:05.03909Z","end":"2026-10-26T20:03:05.210876Z","steps":["trace[1982281340] 'agreement among raft nodes before linearized reading' (duration: 171.4095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:03:05.210863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.277589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-26T20:03:05.210954Z","caller":"traceutil/trace.go:171","msg":"trace[2124786028] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:29423; }","duration":"159.465004ms","start":"2026-10-26T20:03:05.051468Z","end":"2026-10-26T20:03:05.210933Z","steps":["trace[2124786028] 'agreement among raft nodes before linearized reading' (duration: 159.118905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:03:05.211045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.403679ms","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-10-26T20:03:05.211246Z","caller":"traceutil/trace.go:171","msg":"trace[846809054] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29423; }","duration":"139.695516ms","start":"2026-10-26T20:03:05.071514Z","end":"2026-10-26T20:03:05.211209Z","steps":["trace[846809054] 'agreement among raft nodes before linearized reading' (duration: 139.084151ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:03:05.418436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.435614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:03:05.41894Z","caller":"traceutil/trace.go:171","msg":"trace[506341409] transaction","detail":"{read_only:false; response_revision:29425; number_of_response:1; }","duration":"202.545761ms","start":"2026-10-26T20:03:05.216359Z","end":"2026-10-26T20:03:05.418905Z","steps":["trace[506341409] 'process raft request' (duration: 63.46817ms)","trace[506341409] 'compare' (duration: 138.29778ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:03:05.419141Z","caller":"traceutil/trace.go:171","msg":"trace[1836283695] transaction","detail":"{read_only:false; response_revision:29428; number_of_response:1; }","duration":"100.568122ms","start":"2026-10-26T20:03:05.318548Z","end":"2026-10-26T20:03:05.419116Z","steps":["trace[1836283695] 'process raft request' (duration: 100.429098ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:03:05.419259Z","caller":"traceutil/trace.go:171","msg":"trace[890388915] transaction","detail":"{read_only:false; response_revision:29426; number_of_response:1; }","duration":"202.078729ms","start":"2026-10-26T20:03:05.217171Z","end":"2026-10-26T20:03:05.41925Z","steps":["trace[890388915] 'process raft request' (duration: 201.646877ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:03:05.419445Z","caller":"traceutil/trace.go:171","msg":"trace[211478929] linearizableReadLoop","detail":"{readStateIndex:30779; appliedIndex:30776; }","duration":"107.067259ms","start":"2026-10-26T20:03:05.312367Z","end":"2026-10-26T20:03:05.419434Z","steps":["trace[211478929] 'read index received' (duration: 82.032µs)","trace[211478929] 'applied index is now lower than readState.Index' (duration: 106.984197ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:03:05.419525Z","caller":"traceutil/trace.go:171","msg":"trace[345668561] transaction","detail":"{read_only:false; response_revision:29427; number_of_response:1; }","duration":"202.180942ms","start":"2026-10-26T20:03:05.217336Z","end":"2026-10-26T20:03:05.419517Z","steps":["trace[345668561] 'process raft request' (duration: 201.586916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:03:05.419701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.322996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-10-26T20:03:05.419728Z","caller":"traceutil/trace.go:171","msg":"trace[1971548902] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:29428; }","duration":"107.401798ms","start":"2026-10-26T20:03:05.312317Z","end":"2026-10-26T20:03:05.419719Z","steps":["trace[1971548902] 'agreement among raft nodes before linearized reading' (duration: 107.261874ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:03:05.772869Z","caller":"traceutil/trace.go:171","msg":"trace[1390855351] linearizableReadLoop","detail":"{readStateIndex:30781; appliedIndex:30780; }","duration":"234.284826ms","start":"2026-10-26T20:03:05.538553Z","end":"2026-10-26T20:03:05.772838Z","steps":["trace[1390855351] 'read index received' (duration: 234.155532ms)","trace[1390855351] 'applied index is now lower than readState.Index' (duration: 127.814µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:03:05.772954Z","caller":"traceutil/trace.go:171","msg":"trace[963040225] transaction","detail":"{read_only:false; response_revision:29429; number_of_response:1; }","duration":"263.786394ms","start":"2026-10-26T20:03:05.509128Z","end":"2026-10-26T20:03:05.772915Z","steps":["trace[963040225] 'process raft request' (duration: 263.534607ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:03:05.773328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.722537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.148\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-26T20:03:05.773477Z","caller":"traceutil/trace.go:171","msg":"trace[1543034678] range","detail":"{range_begin:/registry/masterleases/162.253.55.148; range_end:; response_count:1; response_revision:29429; }","duration":"234.902121ms","start":"2026-10-26T20:03:05.538522Z","end":"2026-10-26T20:03:05.773424Z","steps":["trace[1543034678] 'agreement among raft nodes before linearized reading' (duration: 234.496831ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:03:06.003798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.877608ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-10-26T20:03:06.00396Z","caller":"traceutil/trace.go:171","msg":"trace[1206149970] linearizableReadLoop","detail":"{readStateIndex:30783; appliedIndex:30782; }","duration":"204.885711ms","start":"2026-10-26T20:03:05.799046Z","end":"2026-10-26T20:03:06.003931Z","steps":["trace[1206149970] 'read index received' (duration: 98.8975ms)","trace[1206149970] 'applied index is now lower than readState.Index' (duration: 105.985251ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:03:06.004324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.82964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-10-26T20:03:06.004303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.23516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-10-26T20:03:06.004381Z","caller":"traceutil/trace.go:171","msg":"trace[557860072] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:29430; }","duration":"204.938792ms","start":"2026-10-26T20:03:05.799424Z","end":"2026-10-26T20:03:06.004363Z","steps":["trace[557860072] 'agreement among raft nodes before linearized reading' (duration: 204.707136ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:03:06.004407Z","caller":"traceutil/trace.go:171","msg":"trace[1559693361] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:29430; }","duration":"205.372334ms","start":"2026-10-26T20:03:05.79901Z","end":"2026-10-26T20:03:06.004383Z","steps":["trace[1559693361] 'agreement among raft nodes before linearized reading' (duration: 205.042945ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:03:06.122381Z","caller":"traceutil/trace.go:171","msg":"trace[1461435440] transaction","detail":"{read_only:false; response_revision:29431; number_of_response:1; }","duration":"116.525773ms","start":"2026-10-26T20:03:06.005817Z","end":"2026-10-26T20:03:06.122343Z","steps":["trace[1461435440] 'process raft request' (duration: 101.554938ms)","trace[1461435440] 'compare' (duration: 14.645357ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:03:06.122406Z","caller":"traceutil/trace.go:171","msg":"trace[523361659] transaction","detail":"{read_only:false; response_revision:29433; number_of_response:1; }","duration":"112.179181ms","start":"2026-10-26T20:03:06.010191Z","end":"2026-10-26T20:03:06.12237Z","steps":["trace[523361659] 'process raft request' (duration: 112.126229ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:03:06.122433Z","caller":"traceutil/trace.go:171","msg":"trace[618509654] transaction","detail":"{read_only:false; response_revision:29432; number_of_response:1; }","duration":"112.283263ms","start":"2026-10-26T20:03:06.010113Z","end":"2026-10-26T20:03:06.122397Z","steps":["trace[618509654] 'process raft request' (duration: 112.134389ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:03:26.572218Z","caller":"traceutil/trace.go:171","msg":"trace[1492298922] transaction","detail":"{read_only:false; response_revision:29607; number_of_response:1; }","duration":"151.665055ms","start":"2026-10-26T20:03:26.420524Z","end":"2026-10-26T20:03:26.57219Z","steps":["trace[1492298922] 'process raft request' (duration: 151.458649ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:03:26.583475Z","caller":"traceutil/trace.go:171","msg":"trace[685822646] transaction","detail":"{read_only:false; response_revision:29608; number_of_response:1; }","duration":"161.424605ms","start":"2026-10-26T20:03:26.42203Z","end":"2026-10-26T20:03:26.583455Z","steps":["trace[685822646] 'process raft request' (duration: 161.25515ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:03:26.583723Z","caller":"traceutil/trace.go:171","msg":"trace[1464886537] linearizableReadLoop","detail":"{readStateIndex:30969; appliedIndex:30967; }","duration":"146.148333ms","start":"2026-10-26T20:03:26.437544Z","end":"2026-10-26T20:03:26.583692Z","steps":["trace[1464886537] 'read index received' (duration: 134.534035ms)","trace[1464886537] 'applied index is now lower than readState.Index' (duration: 11.612578ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:03:26.584057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.493232ms","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-10-26T20:03:26.584155Z","caller":"traceutil/trace.go:171","msg":"trace[1724706466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:29609; }","duration":"146.650726ms","start":"2026-10-26T20:03:26.437489Z","end":"2026-10-26T20:03:26.584139Z","steps":["trace[1724706466] 'agreement among raft nodes before linearized reading' (duration: 146.398899ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:04:12.187743Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26728} {"level":"info","ts":"2026-10-26T20:04:12.340651Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26728,"took":"146.765169ms","hash":541403922,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":46743552,"current-db-size-in-use":"47 MB"} {"level":"info","ts":"2026-10-26T20:04:12.340743Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":541403922,"revision":26728,"compact-revision":23379} {"level":"info","ts":"2026-10-26T20:09:12.195808Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30179} {"level":"info","ts":"2026-10-26T20:09:12.366011Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30179,"took":"163.396807ms","hash":1661711919,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":37732352,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-26T20:09:12.366098Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1661711919,"revision":30179,"compact-revision":26728} {"level":"info","ts":"2026-10-26T20:14:12.20398Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33354} {"level":"info","ts":"2026-10-26T20:14:12.357651Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33354,"took":"147.983841ms","hash":3766885930,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":34643968,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-26T20:14:12.357752Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3766885930,"revision":33354,"compact-revision":30179} {"level":"info","ts":"2026-10-26T20:18:18.865325Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a6342599e225e01b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:18:18.872798Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-26T20:18:18.873117Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-26T20:19:12.214666Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36021} {"level":"info","ts":"2026-10-26T20:19:12.355693Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36021,"took":"135.795178ms","hash":1964780840,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":34418688,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-26T20:19:12.355798Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1964780840,"revision":36021,"compact-revision":33354} {"level":"info","ts":"2026-10-26T20:24:12.225255Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38727} {"level":"info","ts":"2026-10-26T20:24:12.398436Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38727,"took":"164.766133ms","hash":1667584373,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":34963456,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-26T20:24:12.398656Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1667584373,"revision":38727,"compact-revision":36021} {"level":"info","ts":"2026-10-26T20:29:12.233318Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41475} {"level":"info","ts":"2026-10-26T20:29:12.391988Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41475,"took":"152.7065ms","hash":705062612,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":34099200,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-26T20:29:12.392067Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":705062612,"revision":41475,"compact-revision":38727} {"level":"info","ts":"2026-10-26T20:34:12.290378Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":44146} {"level":"info","ts":"2026-10-26T20:34:12.797809Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":44146,"took":"501.938361ms","hash":2784087871,"current-db-size-bytes":60653568,"current-db-size":"61 MB","current-db-size-in-use-bytes":33021952,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T20:34:12.798292Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2784087871,"revision":44146,"compact-revision":41475} {"level":"info","ts":"2026-10-26T20:35:51.565686Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a6342599e225e01b","local-member-applied-index":50005,"local-member-snapshot-index":40004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:35:51.572406Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":50005} {"level":"info","ts":"2026-10-26T20:35:51.572546Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":45005}