{"level":"warn","ts":"2026-04-09T12:03:13.144834Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T12:03:13.144942Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-09T12:03:13.145019Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T12:03:13.145034Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-04-09T12:03:13.145053Z","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-04-09T12:03:13.145774Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-04-09T12:03:13.14586Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-09T12:03:13.150505Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.415607ms"} {"level":"info","ts":"2026-04-09T12:03:13.1562Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-04-09T12:03:13.156258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T12:03:13.156281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-04-09T12:03:13.15634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T12:03:13.156358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-04-09T12:03:13.1564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-04-09T12:03:13.16258Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T12:03:13.165836Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T12:03:13.167409Z","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-04-09T12:03:13.169697Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T12:03:13.170012Z","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-04-09T12:03:13.170098Z","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-04-09T12:03:13.170112Z","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-04-09T12:03:13.170116Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T12:03:13.171139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-04-09T12:03:13.171322Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-04-09T12:03:13.172926Z","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-04-09T12:03:13.173074Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-04-09T12:03:13.173206Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-04-09T12:03:13.173354Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T12:03:13.17341Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T12:03:13.757684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T12:03:13.757768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T12:03:13.757844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-04-09T12:03:13.757874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-04-09T12:03:13.757891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-04-09T12:03:13.757924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-04-09T12:03:13.757968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-04-09T12:03:13.760576Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T12:03:13.761607Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T12:03:13.761608Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T12:03:13.761693Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T12:03:13.762262Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T12:03:13.762458Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T12:03:13.762514Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T12:03:13.762554Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T12:03:13.762598Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T12:03:13.765493Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-04-09T12:03:13.765557Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T12:03:34.425521Z","caller":"traceutil/trace.go:171","msg":"trace[488854390] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"148.121668ms","start":"2026-04-09T12:03:34.277372Z","end":"2026-04-09T12:03:34.425494Z","steps":["trace[488854390] 'process raft request' (duration: 147.942093ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:03:47.112031Z","caller":"traceutil/trace.go:171","msg":"trace[159162861] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"108.370243ms","start":"2026-04-09T12:03:47.003626Z","end":"2026-04-09T12:03:47.111996Z","steps":["trace[159162861] 'process raft request' (duration: 105.888059ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:03:47.115473Z","caller":"traceutil/trace.go:171","msg":"trace[1680873282] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:573; }","duration":"109.143216ms","start":"2026-04-09T12:03:47.00631Z","end":"2026-04-09T12:03:47.115453Z","steps":["trace[1680873282] 'read index received' (duration: 103.285051ms)","trace[1680873282] 'applied index is now lower than readState.Index' (duration: 5.857345ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:03:47.11554Z","caller":"traceutil/trace.go:171","msg":"trace[1824405511] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"110.175626ms","start":"2026-04-09T12:03:47.005335Z","end":"2026-04-09T12:03:47.115511Z","steps":["trace[1824405511] 'process raft request' (duration: 109.914449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:03:47.115611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.271449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-09T12:03:47.11569Z","caller":"traceutil/trace.go:171","msg":"trace[1147081185] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:554; }","duration":"109.396222ms","start":"2026-04-09T12:03:47.006281Z","end":"2026-04-09T12:03:47.115677Z","steps":["trace[1147081185] 'agreement among raft nodes before linearized reading' (duration: 109.245158ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:03:49.467922Z","caller":"traceutil/trace.go:171","msg":"trace[952225196] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"330.002782ms","start":"2026-04-09T12:03:49.137895Z","end":"2026-04-09T12:03:49.467898Z","steps":["trace[952225196] 'read index received' (duration: 329.757174ms)","trace[952225196] 'applied index is now lower than readState.Index' (duration: 244.338µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:03:49.467997Z","caller":"traceutil/trace.go:171","msg":"trace[27995075] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"543.51215ms","start":"2026-04-09T12:03:48.924449Z","end":"2026-04-09T12:03:49.467961Z","steps":["trace[27995075] 'process raft request' (duration: 543.264032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:03:49.468063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.161597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T12:03:49.468116Z","caller":"traceutil/trace.go:171","msg":"trace[1052439749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"330.2588ms","start":"2026-04-09T12:03:49.13783Z","end":"2026-04-09T12:03:49.468089Z","steps":["trace[1052439749] 'agreement among raft nodes before linearized reading' (duration: 330.184108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:03:49.468152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:03:49.137805Z","time spent":"330.337852ms","remote":"127.0.0.1:53630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T12:03:49.468458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:03:48.924427Z","time spent":"543.639274ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T12:03:50.640153Z","caller":"traceutil/trace.go:171","msg":"trace[96783676] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"111.043562ms","start":"2026-04-09T12:03:50.529085Z","end":"2026-04-09T12:03:50.640129Z","steps":["trace[96783676] 'process raft request' (duration: 110.885128ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:03:51.8187Z","caller":"traceutil/trace.go:171","msg":"trace[159499619] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"344.975209ms","start":"2026-04-09T12:03:51.473705Z","end":"2026-04-09T12:03:51.81868Z","steps":["trace[159499619] 'read index received' (duration: 344.448123ms)","trace[159499619] 'applied index is now lower than readState.Index' (duration: 526.296µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:03:51.818748Z","caller":"traceutil/trace.go:171","msg":"trace[1634596582] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"544.076058ms","start":"2026-04-09T12:03:51.274641Z","end":"2026-04-09T12:03:51.818717Z","steps":["trace[1634596582] 'process raft request' (duration: 543.719627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:03:51.818878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:03:51.27462Z","time spent":"544.200292ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036119,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T12:03:51.818947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.237316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-09T12:03:51.818965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.003627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-09T12:03:51.819029Z","caller":"traceutil/trace.go:171","msg":"trace[1760495177] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"137.11406ms","start":"2026-04-09T12:03:51.681897Z","end":"2026-04-09T12:03:51.819011Z","steps":["trace[1760495177] 'agreement among raft nodes before linearized reading' (duration: 136.950595ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:03:51.818984Z","caller":"traceutil/trace.go:171","msg":"trace[446100483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:606; }","duration":"345.35548ms","start":"2026-04-09T12:03:51.47362Z","end":"2026-04-09T12:03:51.818976Z","steps":["trace[446100483] 'agreement among raft nodes before linearized reading' (duration: 345.197685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:03:51.819128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:03:51.473586Z","time spent":"345.531865ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-09T12:03:55.121906Z","caller":"traceutil/trace.go:171","msg":"trace[1155064487] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"171.520265ms","start":"2026-04-09T12:03:54.950343Z","end":"2026-04-09T12:03:55.121863Z","steps":["trace[1155064487] 'process raft request' (duration: 170.873536ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:03:55.294365Z","caller":"traceutil/trace.go:171","msg":"trace[743712046] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"103.352593ms","start":"2026-04-09T12:03:55.190975Z","end":"2026-04-09T12:03:55.294328Z","steps":["trace[743712046] 'process raft request' (duration: 103.173587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:04:10.930865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.458291ms","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-04-09T12:04:10.930992Z","caller":"traceutil/trace.go:171","msg":"trace[346867592] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:793; }","duration":"184.666168ms","start":"2026-04-09T12:04:10.746302Z","end":"2026-04-09T12:04:10.930969Z","steps":["trace[346867592] 'range keys from in-memory index tree' (duration: 184.207225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:04:48.23257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.340742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T12:04:48.232673Z","caller":"traceutil/trace.go:171","msg":"trace[600151416] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1251; }","duration":"860.211482ms","start":"2026-04-09T12:04:47.372448Z","end":"2026-04-09T12:04:48.232659Z","steps":["trace[600151416] 'read index received' (duration: 198.374325ms)","trace[600151416] 'applied index is now lower than readState.Index' (duration: 661.835997ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:04:48.232764Z","caller":"traceutil/trace.go:171","msg":"trace[1449554720] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"885.57531ms","start":"2026-04-09T12:04:47.347166Z","end":"2026-04-09T12:04:48.232741Z","steps":["trace[1449554720] 'process raft request' (duration: 223.619579ms)","trace[1449554720] 'compare' (duration: 661.24658ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T12:04:48.232823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:04:47.347139Z","time spent":"885.651862ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T12:04:48.233339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.768529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-09T12:04:48.233474Z","caller":"traceutil/trace.go:171","msg":"trace[403110165] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1215; }","duration":"861.033476ms","start":"2026-04-09T12:04:47.37242Z","end":"2026-04-09T12:04:48.233454Z","steps":["trace[403110165] 'agreement among raft nodes before linearized reading' (duration: 860.549582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:04:48.233527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:04:47.372411Z","time spent":"861.097837ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-09T12:04:48.23511Z","caller":"traceutil/trace.go:171","msg":"trace[565595027] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"439.447013ms","start":"2026-04-09T12:04:47.795641Z","end":"2026-04-09T12:04:48.235088Z","steps":["trace[565595027] 'process raft request' (duration: 439.073822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:04:48.235103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.269948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-09T12:04:48.235228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:04:47.795616Z","time spent":"439.547255ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T12:04:48.235304Z","caller":"traceutil/trace.go:171","msg":"trace[1487123733] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1216; }","duration":"767.571477ms","start":"2026-04-09T12:04:47.467678Z","end":"2026-04-09T12:04:48.235249Z","steps":["trace[1487123733] 'agreement among raft nodes before linearized reading' (duration: 767.219577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:04:48.235409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:04:47.467654Z","time spent":"767.704152ms","remote":"127.0.0.1:54066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-09T12:05:45.259721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113566161998614,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T12:05:45.396744Z","caller":"traceutil/trace.go:171","msg":"trace[1050039266] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"639.372899ms","start":"2026-04-09T12:05:44.757346Z","end":"2026-04-09T12:05:45.396719Z","steps":["trace[1050039266] 'process raft request' (duration: 634.017192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:05:45.396873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:05:44.757313Z","time spent":"639.491583ms","remote":"127.0.0.1:54166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12590,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T12:05:45.396948Z","caller":"traceutil/trace.go:171","msg":"trace[740623823] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1923; }","duration":"638.279407ms","start":"2026-04-09T12:05:44.758646Z","end":"2026-04-09T12:05:45.396926Z","steps":["trace[740623823] 'read index received' (duration: 632.730384ms)","trace[740623823] 'applied index is now lower than readState.Index' (duration: 5.548033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T12:05:45.397096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.423271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T12:05:45.397142Z","caller":"traceutil/trace.go:171","msg":"trace[632070154] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com; range_end:; response_count:0; response_revision:1851; }","duration":"638.515394ms","start":"2026-04-09T12:05:44.758614Z","end":"2026-04-09T12:05:45.397129Z","steps":["trace[632070154] 'agreement among raft nodes before linearized reading' (duration: 638.423391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:05:45.397183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:05:44.758602Z","time spent":"638.569685ms","remote":"127.0.0.1:53694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusters.pxc.percona.com\" "} {"level":"warn","ts":"2026-04-09T12:05:45.397414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.462092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T12:05:45.39745Z","caller":"traceutil/trace.go:171","msg":"trace[1628412782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1851; }","duration":"261.533774ms","start":"2026-04-09T12:05:45.135908Z","end":"2026-04-09T12:05:45.397442Z","steps":["trace[1628412782] 'agreement among raft nodes before linearized reading' (duration: 261.468792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:05:45.397496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.9895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/atmosphere\" ","response":"range_response_count:1 size:1643"} {"level":"info","ts":"2026-04-09T12:05:45.39755Z","caller":"traceutil/trace.go:171","msg":"trace[1060856088] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/atmosphere; range_end:; response_count:1; response_revision:1851; }","duration":"636.076243ms","start":"2026-04-09T12:05:44.761459Z","end":"2026-04-09T12:05:45.397535Z","steps":["trace[1060856088] 'agreement among raft nodes before linearized reading' (duration: 635.919858ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:05:45.396985Z","caller":"traceutil/trace.go:171","msg":"trace[1387289422] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"631.373474ms","start":"2026-04-09T12:05:44.765603Z","end":"2026-04-09T12:05:45.396976Z","steps":["trace[1387289422] 'process raft request' (duration: 631.20495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:05:45.397584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:05:44.761435Z","time spent":"636.140425ms","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":1666,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/atmosphere\" "} {"level":"warn","ts":"2026-04-09T12:05:45.39761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:05:44.765584Z","time spent":"631.999203ms","remote":"127.0.0.1:53694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9355,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T12:05:45.397578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.762816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T12:05:45.397696Z","caller":"traceutil/trace.go:171","msg":"trace[1357574728] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1851; }","duration":"399.92873ms","start":"2026-04-09T12:05:44.997728Z","end":"2026-04-09T12:05:45.397656Z","steps":["trace[1357574728] 'agreement among raft nodes before linearized reading' (duration: 399.738525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:05:45.397754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:05:44.997702Z","time spent":"400.040835ms","remote":"127.0.0.1:53968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-09T12:05:59.514377Z","caller":"traceutil/trace.go:171","msg":"trace[1284612332] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"356.777167ms","start":"2026-04-09T12:05:59.157574Z","end":"2026-04-09T12:05:59.514351Z","steps":["trace[1284612332] 'process raft request' (duration: 356.610342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:05:59.514555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:05:59.157554Z","time spent":"356.913652ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T12:05:59.523651Z","caller":"traceutil/trace.go:171","msg":"trace[580459272] linearizableReadLoop","detail":"{readStateIndex:2119; appliedIndex:2117; }","duration":"342.652483ms","start":"2026-04-09T12:05:59.180971Z","end":"2026-04-09T12:05:59.523623Z","steps":["trace[580459272] 'read index received' (duration: 333.268598ms)","trace[580459272] 'applied index is now lower than readState.Index' (duration: 9.382505ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:05:59.523727Z","caller":"traceutil/trace.go:171","msg":"trace[1947191402] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"354.16563ms","start":"2026-04-09T12:05:59.169532Z","end":"2026-04-09T12:05:59.523697Z","steps":["trace[1947191402] 'process raft request' (duration: 353.939873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:05:59.523859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.837898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T12:05:59.52387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:05:59.169509Z","time spent":"354.282554ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T12:05:59.523991Z","caller":"traceutil/trace.go:171","msg":"trace[188341455] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2038; }","duration":"342.983022ms","start":"2026-04-09T12:05:59.180935Z","end":"2026-04-09T12:05:59.523918Z","steps":["trace[188341455] 'agreement among raft nodes before linearized reading' (duration: 342.832988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:05:59.524059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:05:59.180921Z","time spent":"343.118407ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-04-09T12:05:59.524058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.937213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-09T12:05:59.524124Z","caller":"traceutil/trace.go:171","msg":"trace[1918994199] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2038; }","duration":"182.037926ms","start":"2026-04-09T12:05:59.342067Z","end":"2026-04-09T12:05:59.524105Z","steps":["trace[1918994199] 'agreement among raft nodes before linearized reading' (duration: 181.779808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:06:51.371272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.516321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T12:06:51.37142Z","caller":"traceutil/trace.go:171","msg":"trace[1509414759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2377; }","duration":"234.732368ms","start":"2026-04-09T12:06:51.136661Z","end":"2026-04-09T12:06:51.371394Z","steps":["trace[1509414759] 'range keys from in-memory index tree' (duration: 234.290624ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:06:51.970411Z","caller":"traceutil/trace.go:171","msg":"trace[1943428931] transaction","detail":"{read_only:false; response_revision:2379; number_of_response:1; }","duration":"201.868508ms","start":"2026-04-09T12:06:51.768461Z","end":"2026-04-09T12:06:51.970329Z","steps":["trace[1943428931] 'process raft request' (duration: 177.806979ms)","trace[1943428931] 'compare' (duration: 23.777181ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T12:06:57.301758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.483752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-09T12:06:57.30186Z","caller":"traceutil/trace.go:171","msg":"trace[1348642357] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2404; }","duration":"267.633257ms","start":"2026-04-09T12:06:57.034208Z","end":"2026-04-09T12:06:57.301842Z","steps":["trace[1348642357] 'range keys from in-memory index tree' (duration: 267.359029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:06:57.302301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.153665ms","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-04-09T12:06:57.3024Z","caller":"traceutil/trace.go:171","msg":"trace[1081411044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2404; }","duration":"207.309359ms","start":"2026-04-09T12:06:57.09507Z","end":"2026-04-09T12:06:57.302379Z","steps":["trace[1081411044] 'range keys from in-memory index tree' (duration: 206.955369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:06:57.302434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.476102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-09T12:06:57.302343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.865412ms","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-04-09T12:06:57.302544Z","caller":"traceutil/trace.go:171","msg":"trace[759906956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2404; }","duration":"211.624876ms","start":"2026-04-09T12:06:57.090888Z","end":"2026-04-09T12:06:57.302513Z","steps":["trace[759906956] 'range keys from in-memory index tree' (duration: 211.310016ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:06:57.302583Z","caller":"traceutil/trace.go:171","msg":"trace[2070663551] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2404; }","duration":"215.117219ms","start":"2026-04-09T12:06:57.087426Z","end":"2026-04-09T12:06:57.302543Z","steps":["trace[2070663551] 'range keys from in-memory index tree' (duration: 214.718647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:06:57.302761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.84176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-09T12:06:57.302798Z","caller":"traceutil/trace.go:171","msg":"trace[411628820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2404; }","duration":"253.907532ms","start":"2026-04-09T12:06:57.04888Z","end":"2026-04-09T12:06:57.302788Z","steps":["trace[411628820] 'range keys from in-memory index tree' (duration: 253.738297ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:06:57.302872Z","caller":"traceutil/trace.go:171","msg":"trace[452288512] linearizableReadLoop","detail":"{readStateIndex:2499; appliedIndex:2498; }","duration":"164.56602ms","start":"2026-04-09T12:06:57.138238Z","end":"2026-04-09T12:06:57.302804Z","steps":["trace[452288512] 'read index received' (duration: 66.761638ms)","trace[452288512] 'applied index is now lower than readState.Index' (duration: 97.802722ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:06:57.30298Z","caller":"traceutil/trace.go:171","msg":"trace[1940983525] transaction","detail":"{read_only:false; response_revision:2405; number_of_response:1; }","duration":"205.629689ms","start":"2026-04-09T12:06:57.09732Z","end":"2026-04-09T12:06:57.30295Z","steps":["trace[1940983525] 'process raft request' (duration: 107.847748ms)","trace[1940983525] 'compare' (duration: 97.328318ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T12:06:57.303148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.876259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T12:06:57.303171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.363734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T12:06:57.303229Z","caller":"traceutil/trace.go:171","msg":"trace[822302734] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2405; }","duration":"124.446687ms","start":"2026-04-09T12:06:57.178768Z","end":"2026-04-09T12:06:57.303214Z","steps":["trace[822302734] 'agreement among raft nodes before linearized reading' (duration: 124.349784ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:06:57.303232Z","caller":"traceutil/trace.go:171","msg":"trace[1139581503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2405; }","duration":"165.004912ms","start":"2026-04-09T12:06:57.138194Z","end":"2026-04-09T12:06:57.303199Z","steps":["trace[1139581503] 'agreement among raft nodes before linearized reading' (duration: 164.864298ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:07:40.254964Z","caller":"traceutil/trace.go:171","msg":"trace[1059426093] transaction","detail":"{read_only:false; response_revision:2808; number_of_response:1; }","duration":"102.454863ms","start":"2026-04-09T12:07:40.152358Z","end":"2026-04-09T12:07:40.254813Z","steps":["trace[1059426093] 'process raft request' (duration: 102.130552ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:08:16.353776Z","caller":"traceutil/trace.go:171","msg":"trace[1391941832] linearizableReadLoop","detail":"{readStateIndex:3146; appliedIndex:3145; }","duration":"216.246729ms","start":"2026-04-09T12:08:16.137502Z","end":"2026-04-09T12:08:16.353749Z","steps":["trace[1391941832] 'read index received' (duration: 216.036613ms)","trace[1391941832] 'applied index is now lower than readState.Index' (duration: 205.516µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T12:08:16.353955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.435624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T12:08:16.354009Z","caller":"traceutil/trace.go:171","msg":"trace[1965511800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3029; }","duration":"216.553297ms","start":"2026-04-09T12:08:16.137444Z","end":"2026-04-09T12:08:16.353997Z","steps":["trace[1965511800] 'agreement among raft nodes before linearized reading' (duration: 216.433874ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:08:16.353851Z","caller":"traceutil/trace.go:171","msg":"trace[570553332] transaction","detail":"{read_only:false; response_revision:3029; number_of_response:1; }","duration":"232.396312ms","start":"2026-04-09T12:08:16.121409Z","end":"2026-04-09T12:08:16.353806Z","steps":["trace[570553332] 'process raft request' (duration: 232.105463ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:11:08.988358Z","caller":"traceutil/trace.go:171","msg":"trace[1732129526] linearizableReadLoop","detail":"{readStateIndex:4306; appliedIndex:4304; }","duration":"173.886076ms","start":"2026-04-09T12:11:08.81444Z","end":"2026-04-09T12:11:08.988326Z","steps":["trace[1732129526] 'read index received' (duration: 33.22387ms)","trace[1732129526] 'applied index is now lower than readState.Index' (duration: 140.660516ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:11:08.988425Z","caller":"traceutil/trace.go:171","msg":"trace[193361856] transaction","detail":"{read_only:false; response_revision:4146; number_of_response:1; }","duration":"211.132373ms","start":"2026-04-09T12:11:08.777252Z","end":"2026-04-09T12:11:08.988384Z","steps":["trace[193361856] 'process raft request' (duration: 184.763361ms)","trace[193361856] 'compare' (duration: 26.065563ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T12:11:08.989018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.318919ms","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-04-09T12:11:08.989148Z","caller":"traceutil/trace.go:171","msg":"trace[1879339178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4146; }","duration":"174.749151ms","start":"2026-04-09T12:11:08.81437Z","end":"2026-04-09T12:11:08.98912Z","steps":["trace[1879339178] 'agreement among raft nodes before linearized reading' (duration: 174.056221ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:11:09.041232Z","caller":"traceutil/trace.go:171","msg":"trace[548476718] transaction","detail":"{read_only:false; response_revision:4147; number_of_response:1; }","duration":"179.000075ms","start":"2026-04-09T12:11:08.862127Z","end":"2026-04-09T12:11:09.041127Z","steps":["trace[548476718] 'process raft request' (duration: 178.586933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:11:09.041409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.336333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-09T12:11:09.041543Z","caller":"traceutil/trace.go:171","msg":"trace[2005405424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4147; }","duration":"226.495318ms","start":"2026-04-09T12:11:08.815021Z","end":"2026-04-09T12:11:09.041516Z","steps":["trace[2005405424] 'agreement among raft nodes before linearized reading' (duration: 225.986094ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:12:33.38187Z","caller":"traceutil/trace.go:171","msg":"trace[2074829867] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"146.654974ms","start":"2026-04-09T12:12:33.235186Z","end":"2026-04-09T12:12:33.381841Z","steps":["trace[2074829867] 'process raft request' (duration: 146.50209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:12:39.2454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.992495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T12:12:39.245534Z","caller":"traceutil/trace.go:171","msg":"trace[1418653434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5163; }","duration":"104.205482ms","start":"2026-04-09T12:12:39.141296Z","end":"2026-04-09T12:12:39.245501Z","steps":["trace[1418653434] 'range keys from in-memory index tree' (duration: 103.80193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:12:39.246644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.243569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/envoy-gateway-system/atmosphere-http-redirect\" ","response":"range_response_count:1 size:1773"} {"level":"info","ts":"2026-04-09T12:12:39.246754Z","caller":"traceutil/trace.go:171","msg":"trace[727703143] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/envoy-gateway-system/atmosphere-http-redirect; range_end:; response_count:1; response_revision:5163; }","duration":"197.372853ms","start":"2026-04-09T12:12:39.049353Z","end":"2026-04-09T12:12:39.246725Z","steps":["trace[727703143] 'range keys from in-memory index tree' (duration: 196.399125ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:12:39.491117Z","caller":"traceutil/trace.go:171","msg":"trace[2063676252] linearizableReadLoop","detail":"{readStateIndex:5364; appliedIndex:5362; }","duration":"192.655466ms","start":"2026-04-09T12:12:39.298427Z","end":"2026-04-09T12:12:39.491083Z","steps":["trace[2063676252] 'read index received' (duration: 29.747732ms)","trace[2063676252] 'applied index is now lower than readState.Index' (duration: 162.906064ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:12:39.491265Z","caller":"traceutil/trace.go:171","msg":"trace[1176897685] transaction","detail":"{read_only:false; response_revision:5166; number_of_response:1; }","duration":"202.581185ms","start":"2026-04-09T12:12:39.288651Z","end":"2026-04-09T12:12:39.491232Z","steps":["trace[1176897685] 'process raft request' (duration: 202.199793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:12:39.49133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.873383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T12:12:39.491365Z","caller":"traceutil/trace.go:171","msg":"trace[942494144] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:5166; }","duration":"192.949825ms","start":"2026-04-09T12:12:39.298406Z","end":"2026-04-09T12:12:39.491356Z","steps":["trace[942494144] 'agreement among raft nodes before linearized reading' (duration: 192.824121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:12:39.504315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.104271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-09T12:12:39.504446Z","caller":"traceutil/trace.go:171","msg":"trace[1282444852] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5168; }","duration":"142.265316ms","start":"2026-04-09T12:12:39.362139Z","end":"2026-04-09T12:12:39.504404Z","steps":["trace[1282444852] 'agreement among raft nodes before linearized reading' (duration: 141.912816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:12:51.224866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.379815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T12:12:51.224963Z","caller":"traceutil/trace.go:171","msg":"trace[1724894640] linearizableReadLoop","detail":"{readStateIndex:5626; appliedIndex:5625; }","duration":"166.299902ms","start":"2026-04-09T12:12:51.058651Z","end":"2026-04-09T12:12:51.224951Z","steps":["trace[1724894640] 'read index received' (duration: 54.732µs)","trace[1724894640] 'applied index is now lower than readState.Index' (duration: 166.24403ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:12:51.22517Z","caller":"traceutil/trace.go:171","msg":"trace[920048988] transaction","detail":"{read_only:false; response_revision:5420; number_of_response:1; }","duration":"361.191824ms","start":"2026-04-09T12:12:50.863965Z","end":"2026-04-09T12:12:51.225157Z","steps":["trace[920048988] 'process raft request' (duration: 85.410718ms)","trace[920048988] 'compare' (duration: 274.956083ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T12:12:51.225218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:12:50.863939Z","time spent":"361.255955ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T12:12:51.225894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.233739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:22 size:710656"} {"level":"info","ts":"2026-04-09T12:12:51.225977Z","caller":"traceutil/trace.go:171","msg":"trace[441067102] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:22; response_revision:5420; }","duration":"167.370552ms","start":"2026-04-09T12:12:51.058588Z","end":"2026-04-09T12:12:51.225959Z","steps":["trace[441067102] 'agreement among raft nodes before linearized reading' (duration: 166.814276ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:13:03.622043Z","caller":"traceutil/trace.go:171","msg":"trace[530025888] transaction","detail":"{read_only:false; response_revision:5637; number_of_response:1; }","duration":"136.249078ms","start":"2026-04-09T12:13:03.485756Z","end":"2026-04-09T12:13:03.622006Z","steps":["trace[530025888] 'process raft request' (duration: 38.111045ms)","trace[530025888] 'compare' (duration: 97.490044ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:13:03.622021Z","caller":"traceutil/trace.go:171","msg":"trace[1849097660] transaction","detail":"{read_only:false; response_revision:5638; number_of_response:1; }","duration":"127.986378ms","start":"2026-04-09T12:13:03.493972Z","end":"2026-04-09T12:13:03.621959Z","steps":["trace[1849097660] 'process raft request' (duration: 127.71784ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:13:03.818647Z","caller":"traceutil/trace.go:171","msg":"trace[349789799] transaction","detail":"{read_only:false; response_revision:5645; number_of_response:1; }","duration":"117.578343ms","start":"2026-04-09T12:13:03.701034Z","end":"2026-04-09T12:13:03.818612Z","steps":["trace[349789799] 'process raft request' (duration: 64.68955ms)","trace[349789799] 'compare' (duration: 52.645766ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:13:03.819023Z","caller":"traceutil/trace.go:171","msg":"trace[1304848930] transaction","detail":"{read_only:false; response_revision:5646; number_of_response:1; }","duration":"117.894513ms","start":"2026-04-09T12:13:03.701109Z","end":"2026-04-09T12:13:03.819004Z","steps":["trace[1304848930] 'process raft request' (duration: 117.440819ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:13:03.819165Z","caller":"traceutil/trace.go:171","msg":"trace[390595072] transaction","detail":"{read_only:false; response_revision:5647; number_of_response:1; }","duration":"118.003405ms","start":"2026-04-09T12:13:03.701131Z","end":"2026-04-09T12:13:03.819134Z","steps":["trace[390595072] 'process raft request' (duration: 117.690236ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:13:03.819277Z","caller":"traceutil/trace.go:171","msg":"trace[452707218] transaction","detail":"{read_only:false; response_revision:5648; number_of_response:1; }","duration":"117.108158ms","start":"2026-04-09T12:13:03.702157Z","end":"2026-04-09T12:13:03.819266Z","steps":["trace[452707218] 'process raft request' (duration: 116.923693ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:13:03.819392Z","caller":"traceutil/trace.go:171","msg":"trace[251497366] linearizableReadLoop","detail":"{readStateIndex:5862; appliedIndex:5858; }","duration":"113.018025ms","start":"2026-04-09T12:13:03.706357Z","end":"2026-04-09T12:13:03.819375Z","steps":["trace[251497366] 'read index received' (duration: 59.38423ms)","trace[251497366] 'applied index is now lower than readState.Index' (duration: 53.631455ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:13:03.819574Z","caller":"traceutil/trace.go:171","msg":"trace[2126801320] transaction","detail":"{read_only:false; response_revision:5649; number_of_response:1; }","duration":"109.121816ms","start":"2026-04-09T12:13:03.71044Z","end":"2026-04-09T12:13:03.819562Z","steps":["trace[2126801320] 'process raft request' (duration: 108.841698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:13:03.820964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.17675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-09T12:13:03.821069Z","caller":"traceutil/trace.go:171","msg":"trace[1653990693] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5648; }","duration":"116.314004ms","start":"2026-04-09T12:13:03.704731Z","end":"2026-04-09T12:13:03.821045Z","steps":["trace[1653990693] 'agreement among raft nodes before linearized reading' (duration: 114.721806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:13:04.903421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:13:04.508057Z","time spent":"395.35418ms","remote":"127.0.0.1:36580","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-09T12:13:04.903771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.659089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T12:13:04.903947Z","caller":"traceutil/trace.go:171","msg":"trace[255210636] transaction","detail":"{read_only:false; response_revision:5652; number_of_response:1; }","duration":"1.077060038s","start":"2026-04-09T12:13:03.826873Z","end":"2026-04-09T12:13:04.903933Z","steps":["trace[255210636] 'process raft request' (duration: 1.076995996s)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:13:04.90404Z","caller":"traceutil/trace.go:171","msg":"trace[1801347849] linearizableReadLoop","detail":"{readStateIndex:5866; appliedIndex:5863; }","duration":"889.291147ms","start":"2026-04-09T12:13:04.014714Z","end":"2026-04-09T12:13:04.904005Z","steps":["trace[1801347849] 'read index received' (duration: 426.332468ms)","trace[1801347849] 'applied index is now lower than readState.Index' (duration: 462.957149ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:13:04.904119Z","caller":"traceutil/trace.go:171","msg":"trace[1521693485] transaction","detail":"{read_only:false; response_revision:5651; number_of_response:1; }","duration":"1.080252335s","start":"2026-04-09T12:13:03.82386Z","end":"2026-04-09T12:13:04.904112Z","steps":["trace[1521693485] 'process raft request' (duration: 1.079964387s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:13:04.904185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:13:03.823835Z","time spent":"1.080330257s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T12:13:04.904052Z","caller":"traceutil/trace.go:171","msg":"trace[644508539] transaction","detail":"{read_only:false; response_revision:5650; number_of_response:1; }","duration":"1.080150602s","start":"2026-04-09T12:13:03.823833Z","end":"2026-04-09T12:13:04.903983Z","steps":["trace[644508539] 'process raft request' (duration: 617.196264ms)","trace[644508539] 'compare' (duration: 462.538656ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T12:13:04.904396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.677249ms","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-04-09T12:13:04.904447Z","caller":"traceutil/trace.go:171","msg":"trace[785866820] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5653; }","duration":"889.778242ms","start":"2026-04-09T12:13:04.014657Z","end":"2026-04-09T12:13:04.904435Z","steps":["trace[785866820] 'agreement among raft nodes before linearized reading' (duration: 889.606067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:13:04.904508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:13:04.014632Z","time spent":"889.867135ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T12:13:04.904628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.463776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T12:13:04.90466Z","caller":"traceutil/trace.go:171","msg":"trace[1411945012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5653; }","duration":"766.537117ms","start":"2026-04-09T12:13:04.138113Z","end":"2026-04-09T12:13:04.90465Z","steps":["trace[1411945012] 'agreement among raft nodes before linearized reading' (duration: 766.471165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:13:04.904708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:13:04.138084Z","time spent":"766.6084ms","remote":"127.0.0.1:53630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T12:13:04.90405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:13:03.826867Z","time spent":"1.077158861s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T12:13:04.904796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:13:03.823813Z","time spent":"1.080507733s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T12:13:04.904922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.777074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T12:13:04.904951Z","caller":"traceutil/trace.go:171","msg":"trace[2132389739] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5653; }","duration":"269.838396ms","start":"2026-04-09T12:13:04.635103Z","end":"2026-04-09T12:13:04.904942Z","steps":["trace[2132389739] 'agreement among raft nodes before linearized reading' (duration: 269.787915ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:13:04.904239Z","caller":"traceutil/trace.go:171","msg":"trace[208062029] transaction","detail":"{read_only:false; response_revision:5653; number_of_response:1; }","duration":"596.360812ms","start":"2026-04-09T12:13:04.307866Z","end":"2026-04-09T12:13:04.904227Z","steps":["trace[208062029] 'process raft request' (duration: 596.034072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:13:04.905194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:13:04.307838Z","time spent":"597.30989ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T12:13:04.905304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.597811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q7pgb\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-04-09T12:13:04.905329Z","caller":"traceutil/trace.go:171","msg":"trace[19386048] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q7pgb; range_end:; response_count:1; response_revision:5653; }","duration":"569.655283ms","start":"2026-04-09T12:13:04.335667Z","end":"2026-04-09T12:13:04.905322Z","steps":["trace[19386048] 'agreement among raft nodes before linearized reading' (duration: 569.413194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:13:04.905395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:13:04.335648Z","time spent":"569.739136ms","remote":"127.0.0.1:53844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6474,"request content":"key:\"/registry/pods/openstack/keepalived-q7pgb\" "} {"level":"info","ts":"2026-04-09T12:13:13.79233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3016} {"level":"info","ts":"2026-04-09T12:13:13.897411Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3016,"took":"102.29864ms","hash":2385750882,"current-db-size-bytes":56930304,"current-db-size":"57 MB","current-db-size-in-use-bytes":29732864,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-09T12:13:13.897477Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2385750882,"revision":3016,"compact-revision":-1} {"level":"info","ts":"2026-04-09T12:13:25.139163Z","caller":"traceutil/trace.go:171","msg":"trace[1994583171] linearizableReadLoop","detail":"{readStateIndex:6118; appliedIndex:6117; }","duration":"118.46733ms","start":"2026-04-09T12:13:25.020674Z","end":"2026-04-09T12:13:25.139141Z","steps":["trace[1994583171] 'read index received' (duration: 99.077772ms)","trace[1994583171] 'applied index is now lower than readState.Index' (duration: 19.388748ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:13:25.139294Z","caller":"traceutil/trace.go:171","msg":"trace[577642296] transaction","detail":"{read_only:false; response_revision:5897; number_of_response:1; }","duration":"122.283495ms","start":"2026-04-09T12:13:25.01697Z","end":"2026-04-09T12:13:25.139254Z","steps":["trace[577642296] 'process raft request' (duration: 102.808535ms)","trace[577642296] 'compare' (duration: 19.256033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T12:13:25.139385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.705497ms","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-04-09T12:13:25.139461Z","caller":"traceutil/trace.go:171","msg":"trace[2056922809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5897; }","duration":"118.832131ms","start":"2026-04-09T12:13:25.020611Z","end":"2026-04-09T12:13:25.139444Z","steps":["trace[2056922809] 'agreement among raft nodes before linearized reading' (duration: 118.649645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:13:25.139531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.17583ms","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-04-09T12:13:25.139568Z","caller":"traceutil/trace.go:171","msg":"trace[1978336336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5897; }","duration":"112.267082ms","start":"2026-04-09T12:13:25.027289Z","end":"2026-04-09T12:13:25.139556Z","steps":["trace[1978336336] 'agreement among raft nodes before linearized reading' (duration: 111.981874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:13:25.139511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.2447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q7pgb\" ","response":"range_response_count:1 size:6451"} {"level":"warn","ts":"2026-04-09T12:13:25.139576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.18981ms","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-04-09T12:13:25.139649Z","caller":"traceutil/trace.go:171","msg":"trace[325922854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5897; }","duration":"112.298423ms","start":"2026-04-09T12:13:25.027333Z","end":"2026-04-09T12:13:25.139631Z","steps":["trace[325922854] 'agreement among raft nodes before linearized reading' (duration: 112.079087ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:13:25.139615Z","caller":"traceutil/trace.go:171","msg":"trace[1819077725] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q7pgb; range_end:; response_count:1; response_revision:5897; }","duration":"110.363564ms","start":"2026-04-09T12:13:25.029234Z","end":"2026-04-09T12:13:25.139597Z","steps":["trace[1819077725] 'agreement among raft nodes before linearized reading' (duration: 110.102847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:14:06.363801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.591999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-09T12:14:06.363958Z","caller":"traceutil/trace.go:171","msg":"trace[537767496] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:6247; }","duration":"101.829965ms","start":"2026-04-09T12:14:06.262099Z","end":"2026-04-09T12:14:06.363929Z","steps":["trace[537767496] 'range keys from in-memory index tree' (duration: 101.340391ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:15:28.484886Z","caller":"traceutil/trace.go:171","msg":"trace[609403475] transaction","detail":"{read_only:false; response_revision:6801; number_of_response:1; }","duration":"182.877208ms","start":"2026-04-09T12:15:28.30199Z","end":"2026-04-09T12:15:28.484867Z","steps":["trace[609403475] 'process raft request' (duration: 182.760084ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:15:28.487201Z","caller":"traceutil/trace.go:171","msg":"trace[1335214181] transaction","detail":"{read_only:false; response_revision:6802; number_of_response:1; }","duration":"144.167316ms","start":"2026-04-09T12:15:28.343006Z","end":"2026-04-09T12:15:28.487174Z","steps":["trace[1335214181] 'process raft request' (duration: 143.97065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:30.281372Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113566162011727,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T12:15:30.321406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.69587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T12:15:30.321476Z","caller":"traceutil/trace.go:171","msg":"trace[2060896528] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:6802; }","duration":"941.821753ms","start":"2026-04-09T12:15:29.379638Z","end":"2026-04-09T12:15:30.321459Z","steps":["trace[2060896528] 'count revisions from in-memory index tree' (duration: 941.606787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:30.321502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:29.379619Z","time spent":"941.877015ms","remote":"127.0.0.1:54126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-04-09T12:15:30.321832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587744834s","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:5"} {"level":"info","ts":"2026-04-09T12:15:30.321868Z","caller":"traceutil/trace.go:171","msg":"trace[983677444] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:6802; }","duration":"1.587820766s","start":"2026-04-09T12:15:28.734037Z","end":"2026-04-09T12:15:30.321858Z","steps":["trace[983677444] 'count revisions from in-memory index tree' (duration: 1.587650171s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:30.321886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:28.734015Z","time spent":"1.587865777s","remote":"127.0.0.1:49956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-04-09T12:15:30.322082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541308755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T12:15:30.32211Z","caller":"traceutil/trace.go:171","msg":"trace[1970759591] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6802; }","duration":"1.541379747s","start":"2026-04-09T12:15:28.780723Z","end":"2026-04-09T12:15:30.322103Z","steps":["trace[1970759591] 'count revisions from in-memory index tree' (duration: 1.541220543s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:30.322128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:28.7807Z","time spent":"1.541422688s","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-09T12:15:30.322098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.756386475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-04-09T12:15:30.322183Z","caller":"traceutil/trace.go:171","msg":"trace[379250400] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:6802; }","duration":"1.756518879s","start":"2026-04-09T12:15:28.565648Z","end":"2026-04-09T12:15:30.322167Z","steps":["trace[379250400] 'range keys from in-memory index tree' (duration: 1.75622672s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:30.322211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:28.565628Z","time spent":"1.756576141s","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5674,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-09T12:15:30.322328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.671290576s","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-04-09T12:15:30.322387Z","caller":"traceutil/trace.go:171","msg":"trace[1728616380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6802; }","duration":"1.671368879s","start":"2026-04-09T12:15:28.651006Z","end":"2026-04-09T12:15:30.322374Z","steps":["trace[1728616380] 'range keys from in-memory index tree' (duration: 1.671122001s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:30.322424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:28.650994Z","time spent":"1.67142001s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-09T12:15:30.322553Z","caller":"traceutil/trace.go:171","msg":"trace[1577949827] transaction","detail":"{read_only:false; response_revision:6803; number_of_response:1; }","duration":"831.847418ms","start":"2026-04-09T12:15:29.490697Z","end":"2026-04-09T12:15:30.322545Z","steps":["trace[1577949827] 'process raft request' (duration: 828.075567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:30.322621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:29.490677Z","time spent":"831.898938ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T12:15:30.324739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18348445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T12:15:30.324846Z","caller":"traceutil/trace.go:171","msg":"trace[2058608391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6802; }","duration":"1.186152199s","start":"2026-04-09T12:15:29.138672Z","end":"2026-04-09T12:15:30.324824Z","steps":["trace[2058608391] 'range keys from in-memory index tree' (duration: 1.183337956s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:30.324903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:29.138649Z","time spent":"1.186237101s","remote":"127.0.0.1:53630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T12:15:30.329514Z","caller":"traceutil/trace.go:171","msg":"trace[1507366813] transaction","detail":"{read_only:false; response_revision:6804; number_of_response:1; }","duration":"504.266867ms","start":"2026-04-09T12:15:29.825231Z","end":"2026-04-09T12:15:30.329498Z","steps":["trace[1507366813] 'process raft request' (duration: 504.083162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:30.32964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:29.825214Z","time spent":"504.357109ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T12:15:30.329659Z","caller":"traceutil/trace.go:171","msg":"trace[2056855536] linearizableReadLoop","detail":"{readStateIndex:7054; appliedIndex:7051; }","duration":"549.336285ms","start":"2026-04-09T12:15:29.780301Z","end":"2026-04-09T12:15:30.329637Z","steps":["trace[2056855536] 'read index received' (duration: 538.483078ms)","trace[2056855536] 'applied index is now lower than readState.Index' (duration: 10.851677ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:15:30.32974Z","caller":"traceutil/trace.go:171","msg":"trace[155317766] transaction","detail":"{read_only:false; response_revision:6805; number_of_response:1; }","duration":"275.699542ms","start":"2026-04-09T12:15:30.054025Z","end":"2026-04-09T12:15:30.329724Z","steps":["trace[155317766] 'process raft request' (duration: 275.442174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:30.329813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.51117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-09T12:15:30.329848Z","caller":"traceutil/trace.go:171","msg":"trace[1610392872] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6805; }","duration":"549.596882ms","start":"2026-04-09T12:15:29.78024Z","end":"2026-04-09T12:15:30.329837Z","steps":["trace[1610392872] 'agreement among raft nodes before linearized reading' (duration: 549.481609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:30.329875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:29.780211Z","time spent":"549.657024ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T12:15:30.330586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.797016ms","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-04-09T12:15:30.330626Z","caller":"traceutil/trace.go:171","msg":"trace[837132439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6806; }","duration":"512.954001ms","start":"2026-04-09T12:15:29.81766Z","end":"2026-04-09T12:15:30.330614Z","steps":["trace[837132439] 'agreement among raft nodes before linearized reading' (duration: 512.847637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:30.330642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:29.817642Z","time spent":"512.996962ms","remote":"127.0.0.1:53942","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":"warn","ts":"2026-04-09T12:15:30.33079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.999082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q7pgb\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-04-09T12:15:30.330817Z","caller":"traceutil/trace.go:171","msg":"trace[650968735] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q7pgb; range_end:; response_count:1; response_revision:6806; }","duration":"499.050424ms","start":"2026-04-09T12:15:29.83176Z","end":"2026-04-09T12:15:30.330811Z","steps":["trace[650968735] 'agreement among raft nodes before linearized reading' (duration: 498.968292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:30.330835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:29.831748Z","time spent":"499.082456ms","remote":"127.0.0.1:53844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6474,"request content":"key:\"/registry/pods/openstack/keepalived-q7pgb\" "} {"level":"warn","ts":"2026-04-09T12:15:30.330853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.476372ms","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-04-09T12:15:30.330848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.781017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T12:15:30.330893Z","caller":"traceutil/trace.go:171","msg":"trace[1174551831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6806; }","duration":"505.582125ms","start":"2026-04-09T12:15:29.8253Z","end":"2026-04-09T12:15:30.330882Z","steps":["trace[1174551831] 'agreement among raft nodes before linearized reading' (duration: 505.462052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:30.330945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:29.825288Z","time spent":"505.651997ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-09T12:15:30.330954Z","caller":"traceutil/trace.go:171","msg":"trace[1575208484] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:6806; }","duration":"258.919532ms","start":"2026-04-09T12:15:30.072017Z","end":"2026-04-09T12:15:30.330937Z","steps":["trace[1575208484] 'agreement among raft nodes before linearized reading' (duration: 258.715626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:30.331099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.639686ms","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-04-09T12:15:30.331121Z","caller":"traceutil/trace.go:171","msg":"trace[1282630035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6806; }","duration":"505.685178ms","start":"2026-04-09T12:15:29.82543Z","end":"2026-04-09T12:15:30.331115Z","steps":["trace[1282630035] 'agreement among raft nodes before linearized reading' (duration: 505.632676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:30.331136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:29.825419Z","time spent":"505.713199ms","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T12:15:42.639128Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113566162011895,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T12:15:43.110488Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.054119058s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-09T12:15:43.775291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.554775ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-09T12:15:43.775869Z","caller":"traceutil/trace.go:171","msg":"trace[2007022879] transaction","detail":"{read_only:false; response_revision:6866; number_of_response:1; }","duration":"1.413635182s","start":"2026-04-09T12:15:42.362206Z","end":"2026-04-09T12:15:43.775841Z","steps":["trace[2007022879] 'process raft request' (duration: 1.413198419s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:43.77602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:42.362185Z","time spent":"1.413760626s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T12:15:43.796987Z","caller":"traceutil/trace.go:171","msg":"trace[1555010362] transaction","detail":"{read_only:false; response_revision:6867; number_of_response:1; }","duration":"1.431918446s","start":"2026-04-09T12:15:42.365025Z","end":"2026-04-09T12:15:43.796944Z","steps":["trace[1555010362] 'process raft request' (duration: 1.410799319s)","trace[1555010362] 'compare' (duration: 20.625383ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:15:43.797058Z","caller":"traceutil/trace.go:171","msg":"trace[2147467497] transaction","detail":"{read_only:false; response_revision:6868; number_of_response:1; }","duration":"1.266011564s","start":"2026-04-09T12:15:42.530998Z","end":"2026-04-09T12:15:43.79701Z","steps":["trace[2147467497] 'process raft request' (duration: 1.265894671s)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:15:43.797152Z","caller":"traceutil/trace.go:171","msg":"trace[2071841169] transaction","detail":"{read_only:false; response_revision:6869; number_of_response:1; }","duration":"1.240471526s","start":"2026-04-09T12:15:42.556651Z","end":"2026-04-09T12:15:43.797123Z","steps":["trace[2071841169] 'process raft request' (duration: 1.240311542s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:43.797196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:42.365009Z","time spent":"1.43205236s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T12:15:43.797258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.659169302s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T12:15:43.797321Z","caller":"traceutil/trace.go:171","msg":"trace[1816781174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6869; }","duration":"1.659293856s","start":"2026-04-09T12:15:42.138015Z","end":"2026-04-09T12:15:43.797309Z","steps":["trace[1816781174] 'agreement among raft nodes before linearized reading' (duration: 1.659158342s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:43.797332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:42.556624Z","time spent":"1.2406086s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T12:15:43.797343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:42.530975Z","time spent":"1.26622291s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T12:15:43.797356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:42.137993Z","time spent":"1.659353357s","remote":"127.0.0.1:53630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T12:15:43.797102Z","caller":"traceutil/trace.go:171","msg":"trace[2026991148] linearizableReadLoop","detail":"{readStateIndex:7121; appliedIndex:7116; }","duration":"1.659007087s","start":"2026-04-09T12:15:42.138065Z","end":"2026-04-09T12:15:43.797072Z","steps":["trace[2026991148] 'read index received' (duration: 972.684526ms)","trace[2026991148] 'applied index is now lower than readState.Index' (duration: 686.321621ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T12:15:43.797545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.525724179s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T12:15:43.797571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.135811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T12:15:43.797601Z","caller":"traceutil/trace.go:171","msg":"trace[356502115] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:6869; }","duration":"1.525838084s","start":"2026-04-09T12:15:42.271747Z","end":"2026-04-09T12:15:43.797585Z","steps":["trace[356502115] 'agreement among raft nodes before linearized reading' (duration: 1.525723079s)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:15:43.797603Z","caller":"traceutil/trace.go:171","msg":"trace[938607368] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:6869; }","duration":"693.202083ms","start":"2026-04-09T12:15:43.104391Z","end":"2026-04-09T12:15:43.797593Z","steps":["trace[938607368] 'agreement among raft nodes before linearized reading' (duration: 693.144111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:43.79764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:43.104374Z","time spent":"693.259335ms","remote":"127.0.0.1:33438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"warn","ts":"2026-04-09T12:15:43.797643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:42.271723Z","time spent":"1.525911175s","remote":"127.0.0.1:42492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"warn","ts":"2026-04-09T12:15:43.797665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.411493969s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-09T12:15:43.79769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.40847318s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-09T12:15:43.797713Z","caller":"traceutil/trace.go:171","msg":"trace[1043165734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6869; }","duration":"1.411552051s","start":"2026-04-09T12:15:42.386149Z","end":"2026-04-09T12:15:43.797701Z","steps":["trace[1043165734] 'agreement among raft nodes before linearized reading' (duration: 1.411385406s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:43.797715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403463464s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q7pgb\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-04-09T12:15:43.797787Z","caller":"traceutil/trace.go:171","msg":"trace[1065771278] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q7pgb; range_end:; response_count:1; response_revision:6869; }","duration":"1.403567327s","start":"2026-04-09T12:15:42.394203Z","end":"2026-04-09T12:15:43.79777Z","steps":["trace[1065771278] 'agreement among raft nodes before linearized reading' (duration: 1.403312949s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:43.797841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.410039867s","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-04-09T12:15:43.797855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.416070253s","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-04-09T12:15:43.797859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:42.394188Z","time spent":"1.403657539s","remote":"127.0.0.1:53844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6474,"request content":"key:\"/registry/pods/openstack/keepalived-q7pgb\" "} {"level":"info","ts":"2026-04-09T12:15:43.797878Z","caller":"traceutil/trace.go:171","msg":"trace[1934432291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6869; }","duration":"1.410088268s","start":"2026-04-09T12:15:42.387782Z","end":"2026-04-09T12:15:43.79787Z","steps":["trace[1934432291] 'agreement among raft nodes before linearized reading' (duration: 1.409980925s)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:15:43.797889Z","caller":"traceutil/trace.go:171","msg":"trace[2067077774] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6869; }","duration":"1.416141615s","start":"2026-04-09T12:15:42.381739Z","end":"2026-04-09T12:15:43.797881Z","steps":["trace[2067077774] 'agreement among raft nodes before linearized reading' (duration: 1.416029452s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:43.797919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:42.387776Z","time spent":"1.41013275s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T12:15:43.797929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:42.381711Z","time spent":"1.416210007s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T12:15:43.797747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:42.386144Z","time spent":"1.411595842s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T12:15:43.797949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.465017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-09T12:15:43.797861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.763527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-09T12:15:43.797991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243773614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-04-09T12:15:43.798023Z","caller":"traceutil/trace.go:171","msg":"trace[1026402702] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:6869; }","duration":"644.976683ms","start":"2026-04-09T12:15:43.153036Z","end":"2026-04-09T12:15:43.798013Z","steps":["trace[1026402702] 'agreement among raft nodes before linearized reading' (duration: 644.756737ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:15:43.79802Z","caller":"traceutil/trace.go:171","msg":"trace[1606818185] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:6869; }","duration":"896.582451ms","start":"2026-04-09T12:15:42.901419Z","end":"2026-04-09T12:15:43.798002Z","steps":["trace[1606818185] 'agreement among raft nodes before linearized reading' (duration: 896.474208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:43.798066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:43.153012Z","time spent":"645.043485ms","remote":"127.0.0.1:54166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":10,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-09T12:15:43.798084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:42.901398Z","time spent":"896.668793ms","remote":"127.0.0.1:33392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"info","ts":"2026-04-09T12:15:43.798026Z","caller":"traceutil/trace.go:171","msg":"trace[1820743054] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:6869; }","duration":"1.243811345s","start":"2026-04-09T12:15:42.554204Z","end":"2026-04-09T12:15:43.798015Z","steps":["trace[1820743054] 'agreement among raft nodes before linearized reading' (duration: 1.243695022s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:43.798224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:42.554147Z","time spent":"1.244064433s","remote":"127.0.0.1:53826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":698,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"warn","ts":"2026-04-09T12:15:43.797882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412855799s","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-04-09T12:15:43.798318Z","caller":"traceutil/trace.go:171","msg":"trace[998608990] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6869; }","duration":"1.413320753s","start":"2026-04-09T12:15:42.384985Z","end":"2026-04-09T12:15:43.798306Z","steps":["trace[998608990] 'agreement among raft nodes before linearized reading' (duration: 1.412682894s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:43.798361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:42.38497Z","time spent":"1.413380615s","remote":"127.0.0.1:53942","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-04-09T12:15:43.797739Z","caller":"traceutil/trace.go:171","msg":"trace[523231439] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6869; }","duration":"1.408532963s","start":"2026-04-09T12:15:42.389196Z","end":"2026-04-09T12:15:43.797729Z","steps":["trace[523231439] 'agreement among raft nodes before linearized reading' (duration: 1.408386949s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:15:43.798504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:15:42.389185Z","time spent":"1.409292395s","remote":"127.0.0.1:53942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-09T12:16:05.173356Z","caller":"traceutil/trace.go:171","msg":"trace[1515741819] transaction","detail":"{read_only:false; response_revision:6988; number_of_response:1; }","duration":"133.613728ms","start":"2026-04-09T12:16:05.039721Z","end":"2026-04-09T12:16:05.173335Z","steps":["trace[1515741819] 'process raft request' (duration: 133.466294ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:18:13.798581Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5812} {"level":"info","ts":"2026-04-09T12:18:13.882984Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5812,"took":"82.66446ms","hash":3963247386,"current-db-size-bytes":56930304,"current-db-size":"57 MB","current-db-size-in-use-bytes":26050560,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-09T12:18:13.883072Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3963247386,"revision":5812,"compact-revision":3016} {"level":"info","ts":"2026-04-09T12:19:50.915587Z","caller":"traceutil/trace.go:171","msg":"trace[407213776] transaction","detail":"{read_only:false; response_revision:8723; number_of_response:1; }","duration":"122.493015ms","start":"2026-04-09T12:19:50.793059Z","end":"2026-04-09T12:19:50.915552Z","steps":["trace[407213776] 'process raft request' (duration: 122.224767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:20:22.324842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.585366ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-09T12:20:22.325015Z","caller":"traceutil/trace.go:171","msg":"trace[1125489879] linearizableReadLoop","detail":"{readStateIndex:9266; appliedIndex:9265; }","duration":"186.258626ms","start":"2026-04-09T12:20:22.138741Z","end":"2026-04-09T12:20:22.324999Z","steps":["trace[1125489879] 'read index received' (duration: 59.612µs)","trace[1125489879] 'applied index is now lower than readState.Index' (duration: 186.197624ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T12:20:22.325108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.375699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T12:20:22.325158Z","caller":"traceutil/trace.go:171","msg":"trace[174628093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8942; }","duration":"186.480102ms","start":"2026-04-09T12:20:22.138668Z","end":"2026-04-09T12:20:22.325148Z","steps":["trace[174628093] 'agreement among raft nodes before linearized reading' (duration: 186.390289ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:20:27.023856Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002452.wal"} {"level":"info","ts":"2026-04-09T12:20:32.154289Z","caller":"traceutil/trace.go:171","msg":"trace[2084303253] transaction","detail":"{read_only:false; response_revision:9042; number_of_response:1; }","duration":"154.573341ms","start":"2026-04-09T12:20:31.999682Z","end":"2026-04-09T12:20:32.154255Z","steps":["trace[2084303253] 'process raft request' (duration: 154.323734ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:20:33.099454Z","caller":"traceutil/trace.go:171","msg":"trace[2119129188] transaction","detail":"{read_only:false; response_revision:9048; number_of_response:1; }","duration":"178.809539ms","start":"2026-04-09T12:20:32.920618Z","end":"2026-04-09T12:20:33.099427Z","steps":["trace[2119129188] 'process raft request' (duration: 149.500125ms)","trace[2119129188] 'compare' (duration: 29.017306ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:20:33.099933Z","caller":"traceutil/trace.go:171","msg":"trace[721223040] transaction","detail":"{read_only:false; response_revision:9050; number_of_response:1; }","duration":"170.688101ms","start":"2026-04-09T12:20:32.929236Z","end":"2026-04-09T12:20:33.099924Z","steps":["trace[721223040] 'process raft request' (duration: 170.568647ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:20:33.099979Z","caller":"traceutil/trace.go:171","msg":"trace[1845132418] transaction","detail":"{read_only:false; response_revision:9049; number_of_response:1; }","duration":"173.724041ms","start":"2026-04-09T12:20:32.92623Z","end":"2026-04-09T12:20:33.099954Z","steps":["trace[1845132418] 'process raft request' (duration: 173.122763ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:20:33.100045Z","caller":"traceutil/trace.go:171","msg":"trace[856464575] linearizableReadLoop","detail":"{readStateIndex:9379; appliedIndex:9375; }","duration":"169.375223ms","start":"2026-04-09T12:20:32.93051Z","end":"2026-04-09T12:20:33.099885Z","steps":["trace[856464575] 'read index received' (duration: 45.222419ms)","trace[856464575] 'applied index is now lower than readState.Index' (duration: 124.152014ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T12:20:33.100256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.726014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-09T12:20:33.100313Z","caller":"traceutil/trace.go:171","msg":"trace[1383825808] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:9050; }","duration":"169.812676ms","start":"2026-04-09T12:20:32.930485Z","end":"2026-04-09T12:20:33.100297Z","steps":["trace[1383825808] 'agreement among raft nodes before linearized reading' (duration: 169.59477ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:20:33.428631Z","caller":"traceutil/trace.go:171","msg":"trace[1319536749] linearizableReadLoop","detail":"{readStateIndex:9380; appliedIndex:9379; }","duration":"301.288034ms","start":"2026-04-09T12:20:33.127319Z","end":"2026-04-09T12:20:33.428607Z","steps":["trace[1319536749] 'read index received' (duration: 301.028677ms)","trace[1319536749] 'applied index is now lower than readState.Index' (duration: 257.957µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:20:33.428706Z","caller":"traceutil/trace.go:171","msg":"trace[1447577233] transaction","detail":"{read_only:false; response_revision:9051; number_of_response:1; }","duration":"321.015219ms","start":"2026-04-09T12:20:33.10766Z","end":"2026-04-09T12:20:33.428676Z","steps":["trace[1447577233] 'process raft request' (duration: 320.68881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:20:33.428833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:20:33.10764Z","time spent":"321.121393ms","remote":"127.0.0.1:53984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1101,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T12:20:33.428853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.517021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3565"} {"level":"info","ts":"2026-04-09T12:20:33.428889Z","caller":"traceutil/trace.go:171","msg":"trace[1047458854] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:9051; }","duration":"301.590652ms","start":"2026-04-09T12:20:33.127287Z","end":"2026-04-09T12:20:33.428877Z","steps":["trace[1047458854] 'agreement among raft nodes before linearized reading' (duration: 301.452358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:20:33.428929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T12:20:33.127269Z","time spent":"301.651554ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3588,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-04-09T12:21:31.39342Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-09T12:21:31.39834Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-09T12:21:31.398467Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-09T12:23:13.811206Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7976} {"level":"info","ts":"2026-04-09T12:23:13.869711Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7976,"took":"56.388994ms","hash":4034239546,"current-db-size-bytes":56930304,"current-db-size":"57 MB","current-db-size-in-use-bytes":27983872,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-09T12:23:13.869784Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4034239546,"revision":7976,"compact-revision":5812} {"level":"warn","ts":"2026-04-09T12:25:33.247341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.324572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T12:25:33.247675Z","caller":"traceutil/trace.go:171","msg":"trace[704847762] transaction","detail":"{read_only:false; response_revision:11648; number_of_response:1; }","duration":"286.258128ms","start":"2026-04-09T12:25:32.961351Z","end":"2026-04-09T12:25:33.24761Z","steps":["trace[704847762] 'process raft request' (duration: 38.69622ms)","trace[704847762] 'compare' (duration: 245.960442ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:25:33.247814Z","caller":"traceutil/trace.go:171","msg":"trace[1108398458] linearizableReadLoop","detail":"{readStateIndex:12055; appliedIndex:12052; }","duration":"183.504148ms","start":"2026-04-09T12:25:33.064275Z","end":"2026-04-09T12:25:33.247779Z","steps":["trace[1108398458] 'read index received' (duration: 69.622µs)","trace[1108398458] 'applied index is now lower than readState.Index' (duration: 183.433676ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:25:33.247827Z","caller":"traceutil/trace.go:171","msg":"trace[177047149] transaction","detail":"{read_only:false; response_revision:11649; number_of_response:1; }","duration":"286.438513ms","start":"2026-04-09T12:25:32.961361Z","end":"2026-04-09T12:25:33.247799Z","steps":["trace[177047149] 'process raft request' (duration: 286.189536ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:25:33.247917Z","caller":"traceutil/trace.go:171","msg":"trace[1207307359] transaction","detail":"{read_only:false; response_revision:11650; number_of_response:1; }","duration":"286.192776ms","start":"2026-04-09T12:25:32.961714Z","end":"2026-04-09T12:25:33.247906Z","steps":["trace[1207307359] 'process raft request' (duration: 286.019381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:25:33.248128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.922755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T12:25:33.248193Z","caller":"traceutil/trace.go:171","msg":"trace[2047694097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11650; }","duration":"110.015898ms","start":"2026-04-09T12:25:33.13815Z","end":"2026-04-09T12:25:33.248166Z","steps":["trace[2047694097] 'agreement among raft nodes before linearized reading' (duration: 109.921635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:25:33.248415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.130855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-04-09T12:25:33.248469Z","caller":"traceutil/trace.go:171","msg":"trace[1705022955] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:11650; }","duration":"184.231758ms","start":"2026-04-09T12:25:33.064222Z","end":"2026-04-09T12:25:33.248454Z","steps":["trace[1705022955] 'agreement among raft nodes before linearized reading' (duration: 184.005591ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:28:13.81725Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10381} {"level":"info","ts":"2026-04-09T12:28:13.901129Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10381,"took":"81.57337ms","hash":3876850639,"current-db-size-bytes":56930304,"current-db-size":"57 MB","current-db-size-in-use-bytes":27873280,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-09T12:28:13.901183Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3876850639,"revision":10381,"compact-revision":7976} {"level":"info","ts":"2026-04-09T12:31:53.247344Z","caller":"traceutil/trace.go:171","msg":"trace[1059517973] linearizableReadLoop","detail":"{readStateIndex:14698; appliedIndex:14697; }","duration":"106.507219ms","start":"2026-04-09T12:31:53.140808Z","end":"2026-04-09T12:31:53.247315Z","steps":["trace[1059517973] 'read index received' (duration: 106.288512ms)","trace[1059517973] 'applied index is now lower than readState.Index' (duration: 217.557µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:31:53.247409Z","caller":"traceutil/trace.go:171","msg":"trace[407510915] transaction","detail":"{read_only:false; response_revision:14205; number_of_response:1; }","duration":"113.416228ms","start":"2026-04-09T12:31:53.133958Z","end":"2026-04-09T12:31:53.247374Z","steps":["trace[407510915] 'process raft request' (duration: 113.202352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:31:53.247628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.782996ms","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-04-09T12:31:53.247673Z","caller":"traceutil/trace.go:171","msg":"trace[1674451580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14205; }","duration":"106.869068ms","start":"2026-04-09T12:31:53.140792Z","end":"2026-04-09T12:31:53.247661Z","steps":["trace[1674451580] 'agreement among raft nodes before linearized reading' (duration: 106.670323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:31:53.247754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.747455ms","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-04-09T12:31:53.247788Z","caller":"traceutil/trace.go:171","msg":"trace[2007343159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14205; }","duration":"106.843618ms","start":"2026-04-09T12:31:53.140937Z","end":"2026-04-09T12:31:53.24778Z","steps":["trace[2007343159] 'agreement among raft nodes before linearized reading' (duration: 106.673943ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:32:09.035088Z","caller":"traceutil/trace.go:171","msg":"trace[1056493135] transaction","detail":"{read_only:false; response_revision:14301; number_of_response:1; }","duration":"137.688067ms","start":"2026-04-09T12:32:08.897373Z","end":"2026-04-09T12:32:09.035061Z","steps":["trace[1056493135] 'process raft request' (duration: 137.491621ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:32:09.046246Z","caller":"traceutil/trace.go:171","msg":"trace[242672806] transaction","detail":"{read_only:false; response_revision:14302; number_of_response:1; }","duration":"148.360164ms","start":"2026-04-09T12:32:08.897862Z","end":"2026-04-09T12:32:09.046222Z","steps":["trace[242672806] 'process raft request' (duration: 148.21658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:32:31.941518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.985875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T12:32:31.941724Z","caller":"traceutil/trace.go:171","msg":"trace[840255317] linearizableReadLoop","detail":"{readStateIndex:15128; appliedIndex:15126; }","duration":"209.056195ms","start":"2026-04-09T12:32:31.73265Z","end":"2026-04-09T12:32:31.941706Z","steps":["trace[840255317] 'read index received' (duration: 71.689479ms)","trace[840255317] 'applied index is now lower than readState.Index' (duration: 137.365646ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:32:31.941857Z","caller":"traceutil/trace.go:171","msg":"trace[1805759369] transaction","detail":"{read_only:false; response_revision:14617; number_of_response:1; }","duration":"298.336942ms","start":"2026-04-09T12:32:31.643502Z","end":"2026-04-09T12:32:31.941839Z","steps":["trace[1805759369] 'process raft request' (duration: 160.918875ms)","trace[1805759369] 'compare' (duration: 136.836941ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:32:31.941975Z","caller":"traceutil/trace.go:171","msg":"trace[783824448] transaction","detail":"{read_only:false; response_revision:14618; number_of_response:1; }","duration":"296.915562ms","start":"2026-04-09T12:32:31.645038Z","end":"2026-04-09T12:32:31.941954Z","steps":["trace[783824448] 'process raft request' (duration: 296.599313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:32:31.942017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.321652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T12:32:31.94212Z","caller":"traceutil/trace.go:171","msg":"trace[1099659074] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:14618; }","duration":"209.486007ms","start":"2026-04-09T12:32:31.732612Z","end":"2026-04-09T12:32:31.942098Z","steps":["trace[1099659074] 'agreement among raft nodes before linearized reading' (duration: 209.273901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:32:31.94226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.033605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2b1d97db-9d9e-481b-bcb4-f58638e11c71\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-04-09T12:32:31.942391Z","caller":"traceutil/trace.go:171","msg":"trace[2133065587] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2b1d97db-9d9e-481b-bcb4-f58638e11c71; range_end:; response_count:1; response_revision:14618; }","duration":"182.175809ms","start":"2026-04-09T12:32:31.760192Z","end":"2026-04-09T12:32:31.942367Z","steps":["trace[2133065587] 'agreement among raft nodes before linearized reading' (duration: 181.778207ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:33:13.885236Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12715} {"level":"info","ts":"2026-04-09T12:33:13.959963Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12715,"took":"72.234105ms","hash":1858270014,"current-db-size-bytes":56930304,"current-db-size":"57 MB","current-db-size-in-use-bytes":30949376,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-09T12:33:13.960043Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1858270014,"revision":12715,"compact-revision":10381} {"level":"info","ts":"2026-04-09T12:33:57.310116Z","caller":"traceutil/trace.go:171","msg":"trace[11137775] transaction","detail":"{read_only:false; response_revision:15417; number_of_response:1; }","duration":"159.029781ms","start":"2026-04-09T12:33:57.151043Z","end":"2026-04-09T12:33:57.310073Z","steps":["trace[11137775] 'process raft request' (duration: 145.582593ms)","trace[11137775] 'compare' (duration: 13.258303ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T12:33:57.533328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.495952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T12:33:57.53356Z","caller":"traceutil/trace.go:171","msg":"trace[1033245612] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:15417; }","duration":"108.749409ms","start":"2026-04-09T12:33:57.424783Z","end":"2026-04-09T12:33:57.533533Z","steps":["trace[1033245612] 'count revisions from in-memory index tree' (duration: 108.40792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T12:33:57.762187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.052501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T12:33:57.762313Z","caller":"traceutil/trace.go:171","msg":"trace[768110326] transaction","detail":"{read_only:false; response_revision:15420; number_of_response:1; }","duration":"207.152911ms","start":"2026-04-09T12:33:57.555136Z","end":"2026-04-09T12:33:57.762289Z","steps":["trace[768110326] 'process raft request' (duration: 99.849424ms)","trace[768110326] 'compare' (duration: 106.902056ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T12:33:57.768886Z","caller":"traceutil/trace.go:171","msg":"trace[1576997844] transaction","detail":"{read_only:false; response_revision:15421; number_of_response:1; }","duration":"212.433523ms","start":"2026-04-09T12:33:57.55642Z","end":"2026-04-09T12:33:57.768853Z","steps":["trace[1576997844] 'process raft request' (duration: 212.153315ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:33:57.769318Z","caller":"traceutil/trace.go:171","msg":"trace[167457366] transaction","detail":"{read_only:false; response_revision:15422; number_of_response:1; }","duration":"141.206097ms","start":"2026-04-09T12:33:57.6281Z","end":"2026-04-09T12:33:57.769307Z","steps":["trace[167457366] 'process raft request' (duration: 140.678652ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T12:38:13.891917Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15168} {"level":"info","ts":"2026-04-09T12:38:13.987801Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15168,"took":"92.702718ms","hash":3425055974,"current-db-size-bytes":56930304,"current-db-size":"57 MB","current-db-size-in-use-bytes":27451392,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-09T12:38:13.987856Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3425055974,"revision":15168,"compact-revision":12715}