{"level":"warn","ts":"2026-03-30T10:27:09.063934Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-30T10:27:09.064016Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.124:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.124:2380","--initial-cluster=instance=https://199.19.213.124:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.124:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.124: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-03-30T10:27:09.064079Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-30T10:27:09.064089Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.124:2380"]} {"level":"info","ts":"2026-03-30T10:27:09.064106Z","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-03-30T10:27:09.064777Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.124:2379"]} {"level":"info","ts":"2026-03-30T10:27:09.064858Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.124:2380"],"listen-peer-urls":["https://199.19.213.124:2380"],"advertise-client-urls":["https://199.19.213.124:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.124:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.124: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-03-30T10:27:09.070836Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.660185ms"} {"level":"info","ts":"2026-03-30T10:27:09.077017Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6894f7e0c0041bce","cluster-id":"db11c089a44de10a"} {"level":"info","ts":"2026-03-30T10:27:09.077093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce switched to configuration voters=()"} {"level":"info","ts":"2026-03-30T10:27:09.07712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became follower at term 0"} {"level":"info","ts":"2026-03-30T10:27:09.077138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6894f7e0c0041bce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-30T10:27:09.077151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became follower at term 1"} {"level":"info","ts":"2026-03-30T10:27:09.077209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce switched to configuration voters=(7535920621163912142)"} {"level":"warn","ts":"2026-03-30T10:27:09.08229Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-30T10:27:09.084055Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-30T10:27:09.085313Z","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-03-30T10:27:09.087447Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6894f7e0c0041bce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-30T10:27:09.087604Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6894f7e0c0041bce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-30T10:27:09.087747Z","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-03-30T10:27:09.087852Z","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-03-30T10:27:09.087869Z","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-03-30T10:27:09.090067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce switched to configuration voters=(7535920621163912142)"} {"level":"info","ts":"2026-03-30T10:27:09.090458Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"db11c089a44de10a","local-member-id":"6894f7e0c0041bce","added-peer-id":"6894f7e0c0041bce","added-peer-peer-urls":["https://199.19.213.124:2380"]} {"level":"info","ts":"2026-03-30T10:27:09.092149Z","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-03-30T10:27:09.092237Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.124:2380"} {"level":"info","ts":"2026-03-30T10:27:09.09228Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.124:2380"} {"level":"info","ts":"2026-03-30T10:27:09.092528Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6894f7e0c0041bce","initial-advertise-peer-urls":["https://199.19.213.124:2380"],"listen-peer-urls":["https://199.19.213.124:2380"],"advertise-client-urls":["https://199.19.213.124:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.124:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-30T10:27:09.092596Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-30T10:27:10.079509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce is starting a new election at term 1"} {"level":"info","ts":"2026-03-30T10:27:10.079568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became pre-candidate at term 1"} {"level":"info","ts":"2026-03-30T10:27:10.079587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce received MsgPreVoteResp from 6894f7e0c0041bce at term 1"} {"level":"info","ts":"2026-03-30T10:27:10.079603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became candidate at term 2"} {"level":"info","ts":"2026-03-30T10:27:10.07961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce received MsgVoteResp from 6894f7e0c0041bce at term 2"} {"level":"info","ts":"2026-03-30T10:27:10.079621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6894f7e0c0041bce became leader at term 2"} {"level":"info","ts":"2026-03-30T10:27:10.079629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6894f7e0c0041bce elected leader 6894f7e0c0041bce at term 2"} {"level":"info","ts":"2026-03-30T10:27:10.081544Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6894f7e0c0041bce","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.124:2379]}","request-path":"/0/members/6894f7e0c0041bce/attributes","cluster-id":"db11c089a44de10a","publish-timeout":"7s"} {"level":"info","ts":"2026-03-30T10:27:10.081758Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-30T10:27:10.081842Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-30T10:27:10.081934Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T10:27:10.08256Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-30T10:27:10.082612Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-30T10:27:10.083101Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"db11c089a44de10a","local-member-id":"6894f7e0c0041bce","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T10:27:10.083253Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T10:27:10.083306Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T10:27:10.085039Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.124:2379"} {"level":"info","ts":"2026-03-30T10:27:10.085688Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-30T10:27:28.317592Z","caller":"traceutil/trace.go:171","msg":"trace[1635370326] linearizableReadLoop","detail":"{readStateIndex:468; appliedIndex:467; }","duration":"117.37941ms","start":"2026-03-30T10:27:28.20018Z","end":"2026-03-30T10:27:28.317559Z","steps":["trace[1635370326] 'read index received' (duration: 73.439167ms)","trace[1635370326] 'applied index is now lower than readState.Index' (duration: 43.938743ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T10:27:28.317782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.579983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:27:28.317845Z","caller":"traceutil/trace.go:171","msg":"trace[2057603023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:452; }","duration":"117.709136ms","start":"2026-03-30T10:27:28.200125Z","end":"2026-03-30T10:27:28.317834Z","steps":["trace[2057603023] 'agreement among raft nodes before linearized reading' (duration: 117.575413ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:27:34.114438Z","caller":"traceutil/trace.go:171","msg":"trace[1888266943] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"246.493775ms","start":"2026-03-30T10:27:33.867912Z","end":"2026-03-30T10:27:34.114406Z","steps":["trace[1888266943] 'process raft request' (duration: 246.274331ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:27:39.451447Z","caller":"traceutil/trace.go:171","msg":"trace[1267115293] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"137.577033ms","start":"2026-03-30T10:27:39.313844Z","end":"2026-03-30T10:27:39.451421Z","steps":["trace[1267115293] 'process raft request' (duration: 51.660394ms)","trace[1267115293] 'compare' (duration: 85.786896ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:27:39.459282Z","caller":"traceutil/trace.go:171","msg":"trace[288396601] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"133.464846ms","start":"2026-03-30T10:27:39.325787Z","end":"2026-03-30T10:27:39.459252Z","steps":["trace[288396601] 'process raft request' (duration: 133.242212ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:27:39.459535Z","caller":"traceutil/trace.go:171","msg":"trace[438432091] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"133.603019ms","start":"2026-03-30T10:27:39.325902Z","end":"2026-03-30T10:27:39.459505Z","steps":["trace[438432091] 'process raft request' (duration: 133.265382ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:27:39.459617Z","caller":"traceutil/trace.go:171","msg":"trace[1857881005] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"130.098944ms","start":"2026-03-30T10:27:39.329499Z","end":"2026-03-30T10:27:39.459598Z","steps":["trace[1857881005] 'process raft request' (duration: 129.709546ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:27:39.459875Z","caller":"traceutil/trace.go:171","msg":"trace[174751337] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"125.218194ms","start":"2026-03-30T10:27:39.334644Z","end":"2026-03-30T10:27:39.459862Z","steps":["trace[174751337] 'process raft request' (duration: 124.876798ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:27:39.459972Z","caller":"traceutil/trace.go:171","msg":"trace[1959254758] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"120.185101ms","start":"2026-03-30T10:27:39.339772Z","end":"2026-03-30T10:27:39.459957Z","steps":["trace[1959254758] 'process raft request' (duration: 120.017948ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:27:39.462584Z","caller":"traceutil/trace.go:171","msg":"trace[1828785788] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"120.368984ms","start":"2026-03-30T10:27:39.339858Z","end":"2026-03-30T10:27:39.460227Z","steps":["trace[1828785788] 'process raft request' (duration: 120.034278ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:27:39.463395Z","caller":"traceutil/trace.go:171","msg":"trace[705100161] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"120.905654ms","start":"2026-03-30T10:27:39.342453Z","end":"2026-03-30T10:27:39.463358Z","steps":["trace[705100161] 'process raft request' (duration: 117.616683ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:27:39.465605Z","caller":"traceutil/trace.go:171","msg":"trace[876767073] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"107.054169ms","start":"2026-03-30T10:27:39.358518Z","end":"2026-03-30T10:27:39.465572Z","steps":["trace[876767073] 'process raft request' (duration: 106.422287ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:27:45.808492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055583748,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-30T10:27:45.810381Z","caller":"traceutil/trace.go:171","msg":"trace[884003788] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"565.020711ms","start":"2026-03-30T10:27:45.245342Z","end":"2026-03-30T10:27:45.810362Z","steps":["trace[884003788] 'process raft request' (duration: 564.904629ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:27:45.810729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:27:45.245322Z","time spent":"565.098664ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T10:27:46.238244Z","caller":"traceutil/trace.go:171","msg":"trace[1741532477] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"930.781201ms","start":"2026-03-30T10:27:45.307435Z","end":"2026-03-30T10:27:46.238216Z","steps":["trace[1741532477] 'read index received' (duration: 502.940794ms)","trace[1741532477] 'applied index is now lower than readState.Index' (duration: 427.839607ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:27:46.238332Z","caller":"traceutil/trace.go:171","msg":"trace[1782012163] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"988.244983ms","start":"2026-03-30T10:27:45.25005Z","end":"2026-03-30T10:27:46.238295Z","steps":["trace[1782012163] 'process raft request' (duration: 987.694394ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:27:46.23849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.442354ms","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-03-30T10:27:46.23867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.869736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t6pt7\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-03-30T10:27:46.238678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.383238ms","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-03-30T10:27:46.238689Z","caller":"traceutil/trace.go:171","msg":"trace[1800490028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:617; }","duration":"595.700518ms","start":"2026-03-30T10:27:45.642968Z","end":"2026-03-30T10:27:46.238668Z","steps":["trace[1800490028] 'agreement among raft nodes before linearized reading' (duration: 595.397613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:27:46.238549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:27:45.250018Z","time spent":"988.399976ms","remote":"127.0.0.1:53412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T10:27:46.238769Z","caller":"traceutil/trace.go:171","msg":"trace[1265904388] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t6pt7; range_end:; response_count:1; response_revision:617; }","duration":"560.022399ms","start":"2026-03-30T10:27:45.678721Z","end":"2026-03-30T10:27:46.238743Z","steps":["trace[1265904388] 'agreement among raft nodes before linearized reading' (duration: 559.738344ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:27:46.238784Z","caller":"traceutil/trace.go:171","msg":"trace[825404649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:617; }","duration":"592.541251ms","start":"2026-03-30T10:27:45.646217Z","end":"2026-03-30T10:27:46.238758Z","steps":["trace[825404649] 'agreement among raft nodes before linearized reading' (duration: 592.308986ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:27:46.238822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:27:45.678699Z","time spent":"560.114802ms","remote":"127.0.0.1:53490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-t6pt7\" "} {"level":"warn","ts":"2026-03-30T10:27:46.238834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:27:45.646183Z","time spent":"592.637402ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-30T10:27:46.239174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:27:45.642946Z","time spent":"595.827081ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-30T10:27:46.239278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.841792ms","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-03-30T10:27:46.239321Z","caller":"traceutil/trace.go:171","msg":"trace[1216848589] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"931.924722ms","start":"2026-03-30T10:27:45.307383Z","end":"2026-03-30T10:27:46.239308Z","steps":["trace[1216848589] 'agreement among raft nodes before linearized reading' (duration: 930.945654ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:27:46.239355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:27:45.30736Z","time spent":"931.987894ms","remote":"127.0.0.1:53568","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-03-30T10:27:46.982652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.111413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-30T10:27:46.982751Z","caller":"traceutil/trace.go:171","msg":"trace[1812459408] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"685.895625ms","start":"2026-03-30T10:27:46.296838Z","end":"2026-03-30T10:27:46.982734Z","steps":["trace[1812459408] 'process raft request' (duration: 370.506727ms)","trace[1812459408] 'compare' (duration: 314.685215ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T10:27:46.982801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:27:46.296811Z","time spent":"685.970317ms","remote":"127.0.0.1:53786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-30T10:28:02.498272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.086923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T10:28:02.498475Z","caller":"traceutil/trace.go:171","msg":"trace[633991280] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:806; }","duration":"155.115987ms","start":"2026-03-30T10:28:02.343339Z","end":"2026-03-30T10:28:02.498455Z","steps":["trace[633991280] 'read index received' (duration: 8.489491ms)","trace[633991280] 'applied index is now lower than readState.Index' (duration: 146.625316ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:28:02.498575Z","caller":"traceutil/trace.go:171","msg":"trace[1079082920] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"155.357671ms","start":"2026-03-30T10:28:02.343185Z","end":"2026-03-30T10:28:02.498543Z","steps":["trace[1079082920] 'process raft request' (duration: 155.212399ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:28:02.498618Z","caller":"traceutil/trace.go:171","msg":"trace[2127723776] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"155.450584ms","start":"2026-03-30T10:28:02.343124Z","end":"2026-03-30T10:28:02.498575Z","steps":["trace[2127723776] 'process raft request' (duration: 20.960314ms)","trace[2127723776] 'compare' (duration: 133.947321ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T10:28:02.498708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.220418ms","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-03-30T10:28:02.498748Z","caller":"traceutil/trace.go:171","msg":"trace[17566862] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"147.294699ms","start":"2026-03-30T10:28:02.351444Z","end":"2026-03-30T10:28:02.498739Z","steps":["trace[17566862] 'agreement among raft nodes before linearized reading' (duration: 147.145936ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:28:02.498734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.373042ms","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-03-30T10:28:02.4988Z","caller":"traceutil/trace.go:171","msg":"trace[257542138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"155.467793ms","start":"2026-03-30T10:28:02.343317Z","end":"2026-03-30T10:28:02.498785Z","steps":["trace[257542138] 'agreement among raft nodes before linearized reading' (duration: 155.223378ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:28:02.843604Z","caller":"traceutil/trace.go:171","msg":"trace[1806441805] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"340.541756ms","start":"2026-03-30T10:28:02.503036Z","end":"2026-03-30T10:28:02.843578Z","steps":["trace[1806441805] 'process raft request' (duration: 335.858333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:28:02.844218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:28:02.503012Z","time spent":"340.722879ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T10:28:02.863111Z","caller":"traceutil/trace.go:171","msg":"trace[137310522] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"359.578216ms","start":"2026-03-30T10:28:02.503509Z","end":"2026-03-30T10:28:02.863087Z","steps":["trace[137310522] 'process raft request' (duration: 359.355893ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:28:02.863157Z","caller":"traceutil/trace.go:171","msg":"trace[518134699] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"334.515299ms","start":"2026-03-30T10:28:02.528613Z","end":"2026-03-30T10:28:02.863128Z","steps":["trace[518134699] 'process raft request' (duration: 334.384687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:28:02.86328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:28:02.503497Z","time spent":"359.692848ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T10:28:02.863884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:28:02.528598Z","time spent":"334.963617ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T10:28:03.135614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.284408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.124\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-30T10:28:03.135727Z","caller":"traceutil/trace.go:171","msg":"trace[943892946] range","detail":"{range_begin:/registry/masterleases/199.19.213.124; range_end:; response_count:1; response_revision:788; }","duration":"119.458692ms","start":"2026-03-30T10:28:03.016244Z","end":"2026-03-30T10:28:03.135703Z","steps":["trace[943892946] 'range keys from in-memory index tree' (duration: 119.036453ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:28:17.038345Z","caller":"traceutil/trace.go:171","msg":"trace[2051463550] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:972; }","duration":"112.926106ms","start":"2026-03-30T10:28:16.925399Z","end":"2026-03-30T10:28:17.038325Z","steps":["trace[2051463550] 'read index received' (duration: 112.744923ms)","trace[2051463550] 'applied index is now lower than readState.Index' (duration: 180.533µs)"],"step_count":2} {"level":"warn","ts":"2026-03-30T10:28:17.038525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.136169ms","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-03-30T10:28:17.038554Z","caller":"traceutil/trace.go:171","msg":"trace[692632275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:944; }","duration":"113.2216ms","start":"2026-03-30T10:28:16.925325Z","end":"2026-03-30T10:28:17.038546Z","steps":["trace[692632275] 'agreement among raft nodes before linearized reading' (duration: 113.086858ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:28:17.038844Z","caller":"traceutil/trace.go:171","msg":"trace[2063961529] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"136.739669ms","start":"2026-03-30T10:28:16.902097Z","end":"2026-03-30T10:28:17.038837Z","steps":["trace[2063961529] 'process raft request' (duration: 136.110349ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:28:17.259548Z","caller":"traceutil/trace.go:171","msg":"trace[1530571173] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"216.448193ms","start":"2026-03-30T10:28:17.043036Z","end":"2026-03-30T10:28:17.259484Z","steps":["trace[1530571173] 'process raft request' (duration: 179.106686ms)","trace[1530571173] 'compare' (duration: 37.230365ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:28:17.406872Z","caller":"traceutil/trace.go:171","msg":"trace[1602470029] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"139.760474ms","start":"2026-03-30T10:28:17.267084Z","end":"2026-03-30T10:28:17.406844Z","steps":["trace[1602470029] 'process raft request' (duration: 125.427778ms)","trace[1602470029] 'compare' (duration: 14.226904ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:28:19.456076Z","caller":"traceutil/trace.go:171","msg":"trace[1292734136] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"105.030584ms","start":"2026-03-30T10:28:19.351018Z","end":"2026-03-30T10:28:19.456048Z","steps":["trace[1292734136] 'process raft request' (duration: 22.505962ms)","trace[1292734136] 'compare' (duration: 82.327259ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:28:19.456359Z","caller":"traceutil/trace.go:171","msg":"trace[1550258801] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"105.027554ms","start":"2026-03-30T10:28:19.351313Z","end":"2026-03-30T10:28:19.456341Z","steps":["trace[1550258801] 'process raft request' (duration: 104.681748ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:28:19.456674Z","caller":"traceutil/trace.go:171","msg":"trace[717585102] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"105.046495ms","start":"2026-03-30T10:28:19.351616Z","end":"2026-03-30T10:28:19.456663Z","steps":["trace[717585102] 'process raft request' (duration: 104.629038ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:28:19.456772Z","caller":"traceutil/trace.go:171","msg":"trace[775763639] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"100.976082ms","start":"2026-03-30T10:28:19.355768Z","end":"2026-03-30T10:28:19.456745Z","steps":["trace[775763639] 'process raft request' (duration: 100.86732ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:28:19.456891Z","caller":"traceutil/trace.go:171","msg":"trace[578611477] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"103.006598ms","start":"2026-03-30T10:28:19.353874Z","end":"2026-03-30T10:28:19.456881Z","steps":["trace[578611477] 'process raft request' (duration: 102.695602ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:28:19.457024Z","caller":"traceutil/trace.go:171","msg":"trace[407633451] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"104.813911ms","start":"2026-03-30T10:28:19.352197Z","end":"2026-03-30T10:28:19.457011Z","steps":["trace[407633451] 'process raft request' (duration: 104.25164ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:28:19.457527Z","caller":"traceutil/trace.go:171","msg":"trace[989828247] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1082; }","duration":"102.894947ms","start":"2026-03-30T10:28:19.354593Z","end":"2026-03-30T10:28:19.457488Z","steps":["trace[989828247] 'read index received' (duration: 18.882628ms)","trace[989828247] 'applied index is now lower than readState.Index' (duration: 84.009109ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T10:28:19.457766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.664066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:28:19.457828Z","caller":"traceutil/trace.go:171","msg":"trace[954458972] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck; range_end:; response_count:0; response_revision:1061; }","duration":"100.783388ms","start":"2026-03-30T10:28:19.357024Z","end":"2026-03-30T10:28:19.457807Z","steps":["trace[954458972] 'agreement among raft nodes before linearized reading' (duration: 100.676097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:28:19.457873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.252113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:1 size:3516"} {"level":"info","ts":"2026-03-30T10:28:19.457959Z","caller":"traceutil/trace.go:171","msg":"trace[54045669] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-cainjector; range_end:; response_count:1; response_revision:1061; }","duration":"103.384705ms","start":"2026-03-30T10:28:19.354555Z","end":"2026-03-30T10:28:19.45794Z","steps":["trace[54045669] 'agreement among raft nodes before linearized reading' (duration: 103.11316ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:28:19.773218Z","caller":"traceutil/trace.go:171","msg":"trace[913324872] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"130.396887ms","start":"2026-03-30T10:28:19.642776Z","end":"2026-03-30T10:28:19.773173Z","steps":["trace[913324872] 'process raft request' (duration: 53.315182ms)","trace[913324872] 'compare' (duration: 76.921002ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:28:20.222985Z","caller":"traceutil/trace.go:171","msg":"trace[1582884715] range","detail":"{range_begin:/registry/jobs/cert-manager/cert-manager-startupapicheck; range_end:; response_count:0; response_revision:1084; }","duration":"100.001215ms","start":"2026-03-30T10:28:20.122953Z","end":"2026-03-30T10:28:20.222954Z","steps":["trace[1582884715] 'range keys from in-memory index tree' (duration: 99.875943ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:28:20.714442Z","caller":"traceutil/trace.go:171","msg":"trace[328824685] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"135.233584ms","start":"2026-03-30T10:28:20.579184Z","end":"2026-03-30T10:28:20.714418Z","steps":["trace[328824685] 'process raft request' (duration: 88.050412ms)","trace[328824685] 'compare' (duration: 47.05785ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:28:20.716176Z","caller":"traceutil/trace.go:171","msg":"trace[736863648] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"132.956042ms","start":"2026-03-30T10:28:20.583193Z","end":"2026-03-30T10:28:20.716149Z","steps":["trace[736863648] 'process raft request' (duration: 132.8253ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:28:20.716654Z","caller":"traceutil/trace.go:171","msg":"trace[1072051815] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"134.704123ms","start":"2026-03-30T10:28:20.581934Z","end":"2026-03-30T10:28:20.716639Z","steps":["trace[1072051815] 'process raft request' (duration: 133.92796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:28:31.497136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.496271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:28:31.497229Z","caller":"traceutil/trace.go:171","msg":"trace[54652568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1180; }","duration":"296.635673ms","start":"2026-03-30T10:28:31.200577Z","end":"2026-03-30T10:28:31.497212Z","steps":["trace[54652568] 'range keys from in-memory index tree' (duration: 296.42829ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:28:31.497235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.378298ms","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-03-30T10:28:31.497335Z","caller":"traceutil/trace.go:171","msg":"trace[2065936346] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1180; }","duration":"148.571121ms","start":"2026-03-30T10:28:31.348742Z","end":"2026-03-30T10:28:31.497313Z","steps":["trace[2065936346] 'range keys from in-memory index tree' (duration: 148.204605ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:28:54.233669Z","caller":"traceutil/trace.go:171","msg":"trace[456737830] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"136.026968ms","start":"2026-03-30T10:28:54.097601Z","end":"2026-03-30T10:28:54.233628Z","steps":["trace[456737830] 'process raft request' (duration: 135.733822ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:28:54.248381Z","caller":"traceutil/trace.go:171","msg":"trace[1042935254] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"142.01587ms","start":"2026-03-30T10:28:54.106334Z","end":"2026-03-30T10:28:54.24835Z","steps":["trace[1042935254] 'process raft request' (duration: 141.874818ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:29:18.13351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.500092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bc5d1179-a275-4a96-be10-9fd6c324d85e\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-03-30T10:29:18.13357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.520276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T10:29:18.133638Z","caller":"traceutil/trace.go:171","msg":"trace[1270827888] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bc5d1179-a275-4a96-be10-9fd6c324d85e; range_end:; response_count:1; response_revision:1858; }","duration":"532.668494ms","start":"2026-03-30T10:29:17.600942Z","end":"2026-03-30T10:29:18.13361Z","steps":["trace[1270827888] 'range keys from in-memory index tree' (duration: 532.290838ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:29:18.133678Z","caller":"traceutil/trace.go:171","msg":"trace[1840007238] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1924; }","duration":"367.58181ms","start":"2026-03-30T10:29:17.766079Z","end":"2026-03-30T10:29:18.133661Z","steps":["trace[1840007238] 'read index received' (duration: 121.79229ms)","trace[1840007238] 'applied index is now lower than readState.Index' (duration: 245.78804ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T10:29:18.133705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:29:17.600919Z","time spent":"532.765137ms","remote":"127.0.0.1:53490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bc5d1179-a275-4a96-be10-9fd6c324d85e\" "} {"level":"warn","ts":"2026-03-30T10:29:18.133881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.808356ms","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-03-30T10:29:18.133929Z","caller":"traceutil/trace.go:171","msg":"trace[1740949366] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1859; }","duration":"367.914788ms","start":"2026-03-30T10:29:17.766002Z","end":"2026-03-30T10:29:18.133917Z","steps":["trace[1740949366] 'agreement among raft nodes before linearized reading' (duration: 367.724994ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:29:18.133888Z","caller":"traceutil/trace.go:171","msg":"trace[1173247420] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"440.36774ms","start":"2026-03-30T10:29:17.693314Z","end":"2026-03-30T10:29:18.133682Z","steps":["trace[1173247420] 'process raft request' (duration: 194.6557ms)","trace[1173247420] 'compare' (duration: 245.258481ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T10:29:18.133978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:29:17.765968Z","time spent":"368.000299ms","remote":"127.0.0.1:53568","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-03-30T10:29:18.134085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:29:17.693289Z","time spent":"440.700726ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T10:29:18.146441Z","caller":"traceutil/trace.go:171","msg":"trace[1519302785] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"144.13513ms","start":"2026-03-30T10:29:18.002274Z","end":"2026-03-30T10:29:18.146409Z","steps":["trace[1519302785] 'process raft request' (duration: 143.957016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:29:18.153388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.512848ms","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-03-30T10:29:18.153464Z","caller":"traceutil/trace.go:171","msg":"trace[1458119365] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1862; }","duration":"169.834784ms","start":"2026-03-30T10:29:17.983612Z","end":"2026-03-30T10:29:18.153446Z","steps":["trace[1458119365] 'agreement among raft nodes before linearized reading' (duration: 169.627029ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:29:18.153522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.056462ms","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-03-30T10:29:18.153568Z","caller":"traceutil/trace.go:171","msg":"trace[120845875] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1862; }","duration":"224.142504ms","start":"2026-03-30T10:29:17.929414Z","end":"2026-03-30T10:29:18.153556Z","steps":["trace[120845875] 'agreement among raft nodes before linearized reading' (duration: 224.028012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:29:18.590437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.322919ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-30T10:29:18.59053Z","caller":"traceutil/trace.go:171","msg":"trace[251421195] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1929; }","duration":"312.624838ms","start":"2026-03-30T10:29:18.277891Z","end":"2026-03-30T10:29:18.590515Z","steps":["trace[251421195] 'read index received' (duration: 43.032µs)","trace[251421195] 'applied index is now lower than readState.Index' (duration: 312.580826ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T10:29:18.590593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.70191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:29:18.590626Z","caller":"traceutil/trace.go:171","msg":"trace[2022375867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1863; }","duration":"312.779821ms","start":"2026-03-30T10:29:18.277839Z","end":"2026-03-30T10:29:18.590619Z","steps":["trace[2022375867] 'agreement among raft nodes before linearized reading' (duration: 312.71404ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:29:18.590648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:29:18.277819Z","time spent":"312.824262ms","remote":"127.0.0.1:53270","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-03-30T10:29:18.633128Z","caller":"traceutil/trace.go:171","msg":"trace[734336243] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"310.030839ms","start":"2026-03-30T10:29:18.323068Z","end":"2026-03-30T10:29:18.633099Z","steps":["trace[734336243] 'process raft request' (duration: 309.778394ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:29:18.633296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:29:18.323043Z","time spent":"310.162951ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T10:29:31.185444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.920291ms","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":"info","ts":"2026-03-30T10:29:31.185562Z","caller":"traceutil/trace.go:171","msg":"trace[1449825812] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1967; }","duration":"164.092854ms","start":"2026-03-30T10:29:31.02143Z","end":"2026-03-30T10:29:31.185523Z","steps":["trace[1449825812] 'count revisions from in-memory index tree' (duration: 163.775178ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:29:31.185894Z","caller":"traceutil/trace.go:171","msg":"trace[540716779] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"129.036147ms","start":"2026-03-30T10:29:31.056825Z","end":"2026-03-30T10:29:31.185861Z","steps":["trace[540716779] 'process raft request' (duration: 125.417818ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:29:35.186842Z","caller":"traceutil/trace.go:171","msg":"trace[1974621013] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"223.768718ms","start":"2026-03-30T10:29:34.963043Z","end":"2026-03-30T10:29:35.186812Z","steps":["trace[1974621013] 'process raft request' (duration: 223.626825ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:29:35.188513Z","caller":"traceutil/trace.go:171","msg":"trace[1080493449] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"138.100546ms","start":"2026-03-30T10:29:35.050385Z","end":"2026-03-30T10:29:35.188485Z","steps":["trace[1080493449] 'process raft request' (duration: 137.948343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:30:07.735429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.814273ms","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-03-30T10:30:07.735517Z","caller":"traceutil/trace.go:171","msg":"trace[1161985484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2179; }","duration":"153.960585ms","start":"2026-03-30T10:30:07.581539Z","end":"2026-03-30T10:30:07.735499Z","steps":["trace[1161985484] 'range keys from in-memory index tree' (duration: 153.625569ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:30:07.735416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.862234ms","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-03-30T10:30:07.735634Z","caller":"traceutil/trace.go:171","msg":"trace[1939864370] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2179; }","duration":"196.133458ms","start":"2026-03-30T10:30:07.539471Z","end":"2026-03-30T10:30:07.735605Z","steps":["trace[1939864370] 'range keys from in-memory index tree' (duration: 195.6319ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:30:14.606972Z","caller":"traceutil/trace.go:171","msg":"trace[889062941] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"143.434568ms","start":"2026-03-30T10:30:14.463471Z","end":"2026-03-30T10:30:14.606905Z","steps":["trace[889062941] 'process raft request' (duration: 143.093151ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:30:14.60711Z","caller":"traceutil/trace.go:171","msg":"trace[1612642591] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"143.475798ms","start":"2026-03-30T10:30:14.463499Z","end":"2026-03-30T10:30:14.606974Z","steps":["trace[1612642591] 'process raft request' (duration: 143.294245ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:30:14.839445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.215323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T10:30:14.83959Z","caller":"traceutil/trace.go:171","msg":"trace[481975439] linearizableReadLoop","detail":"{readStateIndex:2295; appliedIndex:2294; }","duration":"209.47094ms","start":"2026-03-30T10:30:14.630097Z","end":"2026-03-30T10:30:14.839568Z","steps":["trace[481975439] 'read index received' (duration: 73.936851ms)","trace[481975439] 'applied index is now lower than readState.Index' (duration: 135.532519ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:30:14.839625Z","caller":"traceutil/trace.go:171","msg":"trace[411606134] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"228.131351ms","start":"2026-03-30T10:30:14.611473Z","end":"2026-03-30T10:30:14.839604Z","steps":["trace[411606134] 'process raft request' (duration: 92.651573ms)","trace[411606134] 'compare' (duration: 135.092961ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T10:30:14.839723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.628033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:30:14.839784Z","caller":"traceutil/trace.go:171","msg":"trace[2136500070] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:2216; }","duration":"209.738845ms","start":"2026-03-30T10:30:14.630028Z","end":"2026-03-30T10:30:14.839767Z","steps":["trace[2136500070] 'agreement among raft nodes before linearized reading' (duration: 209.646003ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:30:48.883989Z","caller":"traceutil/trace.go:171","msg":"trace[448143075] transaction","detail":"{read_only:false; response_revision:2575; number_of_response:1; }","duration":"153.309354ms","start":"2026-03-30T10:30:48.730656Z","end":"2026-03-30T10:30:48.883965Z","steps":["trace[448143075] 'process raft request' (duration: 153.158121ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:30:48.903435Z","caller":"traceutil/trace.go:171","msg":"trace[874829114] transaction","detail":"{read_only:false; response_revision:2576; number_of_response:1; }","duration":"172.618626ms","start":"2026-03-30T10:30:48.730787Z","end":"2026-03-30T10:30:48.903406Z","steps":["trace[874829114] 'process raft request' (duration: 172.467424ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:31:23.122684Z","caller":"traceutil/trace.go:171","msg":"trace[282601135] transaction","detail":"{read_only:false; response_revision:2781; number_of_response:1; }","duration":"120.750981ms","start":"2026-03-30T10:31:23.001872Z","end":"2026-03-30T10:31:23.122623Z","steps":["trace[282601135] 'process raft request' (duration: 120.509326ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:31:23.259178Z","caller":"traceutil/trace.go:171","msg":"trace[1383025472] linearizableReadLoop","detail":"{readStateIndex:2882; appliedIndex:2880; }","duration":"228.990266ms","start":"2026-03-30T10:31:23.03016Z","end":"2026-03-30T10:31:23.25915Z","steps":["trace[1383025472] 'read index received' (duration: 92.249685ms)","trace[1383025472] 'applied index is now lower than readState.Index' (duration: 136.739081ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:31:23.259339Z","caller":"traceutil/trace.go:171","msg":"trace[322115405] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"234.799855ms","start":"2026-03-30T10:31:23.024483Z","end":"2026-03-30T10:31:23.259283Z","steps":["trace[322115405] 'process raft request' (duration: 234.50581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:31:23.259603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.421384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.124\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-30T10:31:23.259691Z","caller":"traceutil/trace.go:171","msg":"trace[250121149] range","detail":"{range_begin:/registry/masterleases/199.19.213.124; range_end:; response_count:1; response_revision:2782; }","duration":"229.572827ms","start":"2026-03-30T10:31:23.0301Z","end":"2026-03-30T10:31:23.259672Z","steps":["trace[250121149] 'agreement among raft nodes before linearized reading' (duration: 229.299141ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:31:23.260049Z","caller":"traceutil/trace.go:171","msg":"trace[1265819354] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"129.197569ms","start":"2026-03-30T10:31:23.130812Z","end":"2026-03-30T10:31:23.26001Z","steps":["trace[1265819354] 'process raft request' (duration: 128.994506ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:33:59.853717Z","caller":"traceutil/trace.go:171","msg":"trace[556406342] linearizableReadLoop","detail":"{readStateIndex:3945; appliedIndex:3944; }","duration":"113.198665ms","start":"2026-03-30T10:33:59.740493Z","end":"2026-03-30T10:33:59.853692Z","steps":["trace[556406342] 'read index received' (duration: 112.970631ms)","trace[556406342] 'applied index is now lower than readState.Index' (duration: 226.814µs)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:33:59.8538Z","caller":"traceutil/trace.go:171","msg":"trace[1843781236] transaction","detail":"{read_only:false; response_revision:3807; number_of_response:1; }","duration":"124.613092ms","start":"2026-03-30T10:33:59.729153Z","end":"2026-03-30T10:33:59.853766Z","steps":["trace[1843781236] 'process raft request' (duration: 124.385077ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:33:59.853962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.443389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:33:59.854053Z","caller":"traceutil/trace.go:171","msg":"trace[608366535] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:3807; }","duration":"113.588562ms","start":"2026-03-30T10:33:59.740444Z","end":"2026-03-30T10:33:59.854032Z","steps":["trace[608366535] 'agreement among raft nodes before linearized reading' (duration: 113.425479ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:35:23.083375Z","caller":"traceutil/trace.go:171","msg":"trace[352073099] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"101.924011ms","start":"2026-03-30T10:35:22.981427Z","end":"2026-03-30T10:35:23.083351Z","steps":["trace[352073099] 'process raft request' (duration: 101.785769ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:35:27.645867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.643961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-30T10:35:27.645983Z","caller":"traceutil/trace.go:171","msg":"trace[932509601] linearizableReadLoop","detail":"{readStateIndex:5174; appliedIndex:5173; }","duration":"187.971994ms","start":"2026-03-30T10:35:27.457993Z","end":"2026-03-30T10:35:27.645965Z","steps":["trace[932509601] 'read index received' (duration: 72.175211ms)","trace[932509601] 'applied index is now lower than readState.Index' (duration: 115.795893ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:35:27.646046Z","caller":"traceutil/trace.go:171","msg":"trace[1709527485] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"199.688675ms","start":"2026-03-30T10:35:27.446347Z","end":"2026-03-30T10:35:27.646035Z","steps":["trace[1709527485] 'process raft request' (duration: 83.818531ms)","trace[1709527485] 'compare' (duration: 115.529658ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T10:35:27.646639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.632805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719280"} {"level":"info","ts":"2026-03-30T10:35:27.646722Z","caller":"traceutil/trace.go:171","msg":"trace[1385217501] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4990; }","duration":"188.758468ms","start":"2026-03-30T10:35:27.457944Z","end":"2026-03-30T10:35:27.646702Z","steps":["trace[1385217501] 'agreement among raft nodes before linearized reading' (duration: 188.091156ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:35:27.656901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.353006ms","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-03-30T10:35:27.656966Z","caller":"traceutil/trace.go:171","msg":"trace[1009671998] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4991; }","duration":"175.448948ms","start":"2026-03-30T10:35:27.481501Z","end":"2026-03-30T10:35:27.65695Z","steps":["trace[1009671998] 'agreement among raft nodes before linearized reading' (duration: 175.254663ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:35:28.25546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.15075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cb6c60c8-30bf-47c3-be31-360761eccc7d\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-30T10:35:28.255548Z","caller":"traceutil/trace.go:171","msg":"trace[712663187] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cb6c60c8-30bf-47c3-be31-360761eccc7d; range_end:; response_count:1; response_revision:4992; }","duration":"246.297562ms","start":"2026-03-30T10:35:28.009232Z","end":"2026-03-30T10:35:28.255529Z","steps":["trace[712663187] 'range keys from in-memory index tree' (duration: 245.983857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:35:28.255644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.048014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cb6c60c8-30bf-47c3-be31-360761eccc7d\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-30T10:35:28.255723Z","caller":"traceutil/trace.go:171","msg":"trace[1234217646] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cb6c60c8-30bf-47c3-be31-360761eccc7d; range_end:; response_count:1; response_revision:4992; }","duration":"162.178277ms","start":"2026-03-30T10:35:28.093525Z","end":"2026-03-30T10:35:28.255703Z","steps":["trace[1234217646] 'range keys from in-memory index tree' (duration: 161.865621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:35:28.256037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.62392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719280"} {"level":"info","ts":"2026-03-30T10:35:28.256129Z","caller":"traceutil/trace.go:171","msg":"trace[1563503614] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4992; }","duration":"288.751273ms","start":"2026-03-30T10:35:27.96736Z","end":"2026-03-30T10:35:28.256111Z","steps":["trace[1563503614] 'range keys from in-memory index tree' (duration: 288.129131ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:35:28.256474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.534703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-9s4qq-tls\" ","response":"range_response_count:1 size:2184"} {"level":"info","ts":"2026-03-30T10:35:28.256542Z","caller":"traceutil/trace.go:171","msg":"trace[1845027787] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-9s4qq-tls; range_end:; response_count:1; response_revision:4992; }","duration":"294.631984ms","start":"2026-03-30T10:35:27.961892Z","end":"2026-03-30T10:35:28.256524Z","steps":["trace[1845027787] 'range keys from in-memory index tree' (duration: 293.868639ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:35:28.285157Z","caller":"traceutil/trace.go:171","msg":"trace[193555150] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"104.340116ms","start":"2026-03-30T10:35:28.180789Z","end":"2026-03-30T10:35:28.285129Z","steps":["trace[193555150] 'process raft request' (duration: 104.212673ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:35:28.625525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.657105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T10:35:28.625667Z","caller":"traceutil/trace.go:171","msg":"trace[499200837] linearizableReadLoop","detail":"{readStateIndex:5183; appliedIndex:5181; }","duration":"138.5096ms","start":"2026-03-30T10:35:28.487148Z","end":"2026-03-30T10:35:28.625657Z","steps":["trace[499200837] 'read index received' (duration: 9.671162ms)","trace[499200837] 'applied index is now lower than readState.Index' (duration: 128.837788ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T10:35:28.625782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.641533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bzkx7\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-30T10:35:28.625795Z","caller":"traceutil/trace.go:171","msg":"trace[2139912245] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bzkx7; range_end:; response_count:1; response_revision:4998; }","duration":"138.686914ms","start":"2026-03-30T10:35:28.487104Z","end":"2026-03-30T10:35:28.625791Z","steps":["trace[2139912245] 'agreement among raft nodes before linearized reading' (duration: 138.586332ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:35:28.625812Z","caller":"traceutil/trace.go:171","msg":"trace[1906453198] transaction","detail":"{read_only:false; response_revision:4997; number_of_response:1; }","duration":"156.822676ms","start":"2026-03-30T10:35:28.468962Z","end":"2026-03-30T10:35:28.625785Z","steps":["trace[1906453198] 'process raft request' (duration: 27.845364ms)","trace[1906453198] 'compare' (duration: 128.488212ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:35:28.625919Z","caller":"traceutil/trace.go:171","msg":"trace[278026841] transaction","detail":"{read_only:false; response_revision:4998; number_of_response:1; }","duration":"144.032205ms","start":"2026-03-30T10:35:28.481881Z","end":"2026-03-30T10:35:28.625913Z","steps":["trace[278026841] 'process raft request' (duration: 143.714578ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:35:39.244403Z","caller":"traceutil/trace.go:171","msg":"trace[942421494] linearizableReadLoop","detail":"{readStateIndex:5417; appliedIndex:5416; }","duration":"152.681879ms","start":"2026-03-30T10:35:39.091665Z","end":"2026-03-30T10:35:39.244347Z","steps":["trace[942421494] 'read index received' (duration: 84.33013ms)","trace[942421494] 'applied index is now lower than readState.Index' (duration: 68.350639ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:35:39.244686Z","caller":"traceutil/trace.go:171","msg":"trace[925771221] transaction","detail":"{read_only:false; response_revision:5224; number_of_response:1; }","duration":"275.463832ms","start":"2026-03-30T10:35:38.969196Z","end":"2026-03-30T10:35:39.244659Z","steps":["trace[925771221] 'process raft request' (duration: 206.834698ms)","trace[925771221] 'compare' (duration: 67.954941ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T10:35:39.244903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.237211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-rv6qw\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-03-30T10:35:39.244973Z","caller":"traceutil/trace.go:171","msg":"trace[737702636] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-rv6qw; range_end:; response_count:1; response_revision:5224; }","duration":"125.319633ms","start":"2026-03-30T10:35:39.119635Z","end":"2026-03-30T10:35:39.244955Z","steps":["trace[737702636] 'agreement among raft nodes before linearized reading' (duration: 125.073618ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:35:39.245169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.466493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-03-30T10:35:39.245265Z","caller":"traceutil/trace.go:171","msg":"trace[2122578778] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5224; }","duration":"153.633066ms","start":"2026-03-30T10:35:39.091612Z","end":"2026-03-30T10:35:39.245245Z","steps":["trace[2122578778] 'agreement among raft nodes before linearized reading' (duration: 153.366771ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:35:39.245647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.053936ms","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-03-30T10:35:39.245769Z","caller":"traceutil/trace.go:171","msg":"trace[1823007731] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5224; }","duration":"100.191179ms","start":"2026-03-30T10:35:39.14553Z","end":"2026-03-30T10:35:39.245721Z","steps":["trace[1823007731] 'agreement among raft nodes before linearized reading' (duration: 99.559727ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:35:47.037089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.796306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T10:35:47.037308Z","caller":"traceutil/trace.go:171","msg":"trace[1246949736] linearizableReadLoop","detail":"{readStateIndex:5561; appliedIndex:5558; }","duration":"330.228274ms","start":"2026-03-30T10:35:46.707063Z","end":"2026-03-30T10:35:47.037291Z","steps":["trace[1246949736] 'read index received' (duration: 122.138752ms)","trace[1246949736] 'applied index is now lower than readState.Index' (duration: 208.088562ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:35:47.037312Z","caller":"traceutil/trace.go:171","msg":"trace[549969349] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"341.812553ms","start":"2026-03-30T10:35:46.695475Z","end":"2026-03-30T10:35:47.037287Z","steps":["trace[549969349] 'process raft request' (duration: 133.731061ms)","trace[549969349] 'compare' (duration: 207.687384ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T10:35:47.037473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:35:46.695455Z","time spent":"341.946496ms","remote":"127.0.0.1:53568","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-03-30T10:35:47.037787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.210321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-30T10:35:47.037831Z","caller":"traceutil/trace.go:171","msg":"trace[725364359] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5367; }","duration":"263.296523ms","start":"2026-03-30T10:35:46.774521Z","end":"2026-03-30T10:35:47.037818Z","steps":["trace[725364359] 'agreement among raft nodes before linearized reading' (duration: 263.164031ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:35:47.038222Z","caller":"traceutil/trace.go:171","msg":"trace[316157448] transaction","detail":"{read_only:false; response_revision:5367; number_of_response:1; }","duration":"333.572037ms","start":"2026-03-30T10:35:46.704639Z","end":"2026-03-30T10:35:47.038211Z","steps":["trace[316157448] 'process raft request' (duration: 332.609249ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:35:47.038244Z","caller":"traceutil/trace.go:171","msg":"trace[1835110662] transaction","detail":"{read_only:false; response_revision:5366; number_of_response:1; }","duration":"341.896584ms","start":"2026-03-30T10:35:46.696317Z","end":"2026-03-30T10:35:47.038214Z","steps":["trace[1835110662] 'process raft request' (duration: 340.865965ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:35:47.038352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:35:46.704609Z","time spent":"333.650449ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T10:35:47.038407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:35:46.696298Z","time spent":"342.015556ms","remote":"127.0.0.1:53568","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-03-30T10:35:47.039868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.795202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bzkx7\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-30T10:35:47.040013Z","caller":"traceutil/trace.go:171","msg":"trace[2007915813] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bzkx7; range_end:; response_count:1; response_revision:5367; }","duration":"332.946806ms","start":"2026-03-30T10:35:46.707023Z","end":"2026-03-30T10:35:47.03997Z","steps":["trace[2007915813] 'agreement among raft nodes before linearized reading' (duration: 331.247844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:35:47.040084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:35:46.70701Z","time spent":"333.060038ms","remote":"127.0.0.1:53490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-bzkx7\" "} {"level":"warn","ts":"2026-03-30T10:35:47.335835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.763339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:35:47.335922Z","caller":"traceutil/trace.go:171","msg":"trace[1080329567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5368; }","duration":"135.897212ms","start":"2026-03-30T10:35:47.200007Z","end":"2026-03-30T10:35:47.335904Z","steps":["trace[1080329567] 'range keys from in-memory index tree' (duration: 135.635777ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:35:47.336031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.821129ms","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-03-30T10:35:47.336073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.826559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-03-30T10:35:47.336124Z","caller":"traceutil/trace.go:171","msg":"trace[1215421155] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:5368; }","duration":"188.929161ms","start":"2026-03-30T10:35:47.147176Z","end":"2026-03-30T10:35:47.336105Z","steps":["trace[1215421155] 'count revisions from in-memory index tree' (duration: 188.715957ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:35:47.336171Z","caller":"traceutil/trace.go:171","msg":"trace[1406435093] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5368; }","duration":"119.969472ms","start":"2026-03-30T10:35:47.216176Z","end":"2026-03-30T10:35:47.336145Z","steps":["trace[1406435093] 'range keys from in-memory index tree' (duration: 119.630516ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:35:47.336368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.965615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6969c934-4b5d-4512-868e-17b7c04b5a78\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-30T10:35:47.336477Z","caller":"traceutil/trace.go:171","msg":"trace[524168627] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6969c934-4b5d-4512-868e-17b7c04b5a78; range_end:; response_count:1; response_revision:5368; }","duration":"142.149478ms","start":"2026-03-30T10:35:47.194301Z","end":"2026-03-30T10:35:47.33645Z","steps":["trace[524168627] 'range keys from in-memory index tree' (duration: 141.713901ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:35:49.427662Z","caller":"traceutil/trace.go:171","msg":"trace[818046308] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"116.18656ms","start":"2026-03-30T10:35:49.311449Z","end":"2026-03-30T10:35:49.427635Z","steps":["trace[818046308] 'process raft request' (duration: 115.904604ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:35:59.250111Z","caller":"traceutil/trace.go:171","msg":"trace[811476270] linearizableReadLoop","detail":"{readStateIndex:5763; appliedIndex:5762; }","duration":"120.572983ms","start":"2026-03-30T10:35:59.129519Z","end":"2026-03-30T10:35:59.250092Z","steps":["trace[811476270] 'read index received' (duration: 108.481564ms)","trace[811476270] 'applied index is now lower than readState.Index' (duration: 12.090559ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:35:59.250204Z","caller":"traceutil/trace.go:171","msg":"trace[1463082652] transaction","detail":"{read_only:false; response_revision:5562; number_of_response:1; }","duration":"171.628365ms","start":"2026-03-30T10:35:59.078568Z","end":"2026-03-30T10:35:59.250196Z","steps":["trace[1463082652] 'process raft request' (duration: 159.498137ms)","trace[1463082652] 'compare' (duration: 11.785662ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T10:35:59.250464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.936529ms","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-03-30T10:35:59.250522Z","caller":"traceutil/trace.go:171","msg":"trace[2144032590] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5562; }","duration":"121.034851ms","start":"2026-03-30T10:35:59.129472Z","end":"2026-03-30T10:35:59.250507Z","steps":["trace[2144032590] 'agreement among raft nodes before linearized reading' (duration: 120.868528ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:35:59.250544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.331763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bzkx7\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-30T10:35:59.250624Z","caller":"traceutil/trace.go:171","msg":"trace[119154002] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bzkx7; range_end:; response_count:1; response_revision:5562; }","duration":"115.438115ms","start":"2026-03-30T10:35:59.135167Z","end":"2026-03-30T10:35:59.250605Z","steps":["trace[119154002] 'agreement among raft nodes before linearized reading' (duration: 115.206531ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:37:10.119194Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3043} {"level":"info","ts":"2026-03-30T10:37:10.208529Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3043,"took":"87.860373ms","hash":1236542507,"current-db-size-bytes":57335808,"current-db-size":"57 MB","current-db-size-in-use-bytes":31928320,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-30T10:37:10.208612Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1236542507,"revision":3043,"compact-revision":-1} {"level":"warn","ts":"2026-03-30T10:37:38.491339Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055596921,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-30T10:37:38.906486Z","caller":"traceutil/trace.go:171","msg":"trace[454416350] transaction","detail":"{read_only:false; response_revision:6345; number_of_response:1; }","duration":"938.06452ms","start":"2026-03-30T10:37:37.968391Z","end":"2026-03-30T10:37:38.906456Z","steps":["trace[454416350] 'process raft request' (duration: 937.884017ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:37:38.90663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:37:37.968376Z","time spent":"938.186982ms","remote":"127.0.0.1:53568","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-03-30T10:37:38.906774Z","caller":"traceutil/trace.go:171","msg":"trace[48899105] transaction","detail":"{read_only:false; response_revision:6346; number_of_response:1; }","duration":"937.825766ms","start":"2026-03-30T10:37:37.968914Z","end":"2026-03-30T10:37:38.90674Z","steps":["trace[48899105] 'process raft request' (duration: 937.505339ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:37:38.906935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:37:37.968826Z","time spent":"938.024589ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T10:37:38.909313Z","caller":"traceutil/trace.go:171","msg":"trace[1377936295] linearizableReadLoop","detail":"{readStateIndex:6570; appliedIndex:6567; }","duration":"918.99031ms","start":"2026-03-30T10:37:37.990293Z","end":"2026-03-30T10:37:38.909284Z","steps":["trace[1377936295] 'read index received' (duration: 916.042065ms)","trace[1377936295] 'applied index is now lower than readState.Index' (duration: 2.946915ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T10:37:38.909625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.314197ms","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-03-30T10:37:38.90972Z","caller":"traceutil/trace.go:171","msg":"trace[1758402166] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:6347; }","duration":"919.482371ms","start":"2026-03-30T10:37:37.990215Z","end":"2026-03-30T10:37:38.909697Z","steps":["trace[1758402166] 'agreement among raft nodes before linearized reading' (duration: 919.182124ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:37:38.909762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:37:37.990203Z","time spent":"919.550462ms","remote":"127.0.0.1:53472","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-03-30T10:37:38.909644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.188816ms","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-03-30T10:37:38.909831Z","caller":"traceutil/trace.go:171","msg":"trace[1880713429] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:6347; }","duration":"883.39698ms","start":"2026-03-30T10:37:38.026409Z","end":"2026-03-30T10:37:38.909806Z","steps":["trace[1880713429] 'agreement among raft nodes before linearized reading' (duration: 883.174676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:37:38.909818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.012817ms","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-03-30T10:37:38.909954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:37:38.026395Z","time spent":"883.513662ms","remote":"127.0.0.1:54732","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":"info","ts":"2026-03-30T10:37:38.909976Z","caller":"traceutil/trace.go:171","msg":"trace[1132733245] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6347; }","duration":"593.19932ms","start":"2026-03-30T10:37:38.316755Z","end":"2026-03-30T10:37:38.909954Z","steps":["trace[1132733245] 'agreement among raft nodes before linearized reading' (duration: 592.848434ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:37:38.910105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:37:38.31674Z","time spent":"593.351913ms","remote":"127.0.0.1:53568","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-03-30T10:37:38.909668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.38032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:37:38.910651Z","caller":"traceutil/trace.go:171","msg":"trace[356230570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6347; }","duration":"710.387939ms","start":"2026-03-30T10:37:38.200244Z","end":"2026-03-30T10:37:38.910632Z","steps":["trace[356230570] 'agreement among raft nodes before linearized reading' (duration: 709.396581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:37:38.910795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:37:38.200224Z","time spent":"710.528101ms","remote":"127.0.0.1:53270","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-03-30T10:38:04.215411Z","caller":"traceutil/trace.go:171","msg":"trace[1349487495] linearizableReadLoop","detail":"{readStateIndex:6703; appliedIndex:6702; }","duration":"347.472219ms","start":"2026-03-30T10:38:03.867916Z","end":"2026-03-30T10:38:04.215388Z","steps":["trace[1349487495] 'read index received' (duration: 347.286176ms)","trace[1349487495] 'applied index is now lower than readState.Index' (duration: 185.233µs)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:38:04.215569Z","caller":"traceutil/trace.go:171","msg":"trace[739261657] transaction","detail":"{read_only:false; response_revision:6474; number_of_response:1; }","duration":"534.341072ms","start":"2026-03-30T10:38:03.681187Z","end":"2026-03-30T10:38:04.215528Z","steps":["trace[739261657] 'process raft request' (duration: 534.014685ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:04.215639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.730054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-30T10:38:04.215679Z","caller":"traceutil/trace.go:171","msg":"trace[1962465017] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6474; }","duration":"347.815716ms","start":"2026-03-30T10:38:03.867845Z","end":"2026-03-30T10:38:04.215661Z","steps":["trace[1962465017] 'agreement among raft nodes before linearized reading' (duration: 347.671212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:04.215707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:03.867827Z","time spent":"347.875288ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-30T10:38:04.215764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:03.681166Z","time spent":"534.508145ms","remote":"127.0.0.1:53568","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-03-30T10:38:04.21582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.534681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:38:04.215915Z","caller":"traceutil/trace.go:171","msg":"trace[1963178049] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:6474; }","duration":"234.691623ms","start":"2026-03-30T10:38:03.981206Z","end":"2026-03-30T10:38:04.215897Z","steps":["trace[1963178049] 'agreement among raft nodes before linearized reading' (duration: 234.52103ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:04.215898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.686362ms","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-03-30T10:38:04.215978Z","caller":"traceutil/trace.go:171","msg":"trace[2121508575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6474; }","duration":"114.808955ms","start":"2026-03-30T10:38:04.101153Z","end":"2026-03-30T10:38:04.215962Z","steps":["trace[2121508575] 'agreement among raft nodes before linearized reading' (duration: 114.57654ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:04.215981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.286141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bzkx7\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-30T10:38:04.216172Z","caller":"traceutil/trace.go:171","msg":"trace[646657472] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bzkx7; range_end:; response_count:1; response_revision:6474; }","duration":"316.522116ms","start":"2026-03-30T10:38:03.899635Z","end":"2026-03-30T10:38:04.216157Z","steps":["trace[646657472] 'agreement among raft nodes before linearized reading' (duration: 315.914075ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:04.216399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:03.899614Z","time spent":"316.73967ms","remote":"127.0.0.1:53490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-bzkx7\" "} {"level":"warn","ts":"2026-03-30T10:38:09.554896Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:10.056244Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:10.556993Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:11.058082Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:11.200255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000133297s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-30T10:38:11.200363Z","caller":"traceutil/trace.go:171","msg":"trace[427256773] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00028584s","start":"2026-03-30T10:38:09.200053Z","end":"2026-03-30T10:38:11.200339Z","steps":["trace[427256773] 'agreement among raft nodes before linearized reading' (duration: 2.000144867s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:11.200421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:09.200044Z","time spent":"2.000365321s","remote":"127.0.0.1:53270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-30T10:38:11.558956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:12.059476Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:12.560609Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:13.061588Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:13.20089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00024909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-30T10:38:13.201012Z","caller":"traceutil/trace.go:171","msg":"trace[744934229] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000430673s","start":"2026-03-30T10:38:11.200557Z","end":"2026-03-30T10:38:13.200987Z","steps":["trace[744934229] 'agreement among raft nodes before linearized reading' (duration: 2.00027836s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:13.201108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:11.200537Z","time spent":"2.000553106s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/30 10:38:13 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:13.56197Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:14.006941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:09.008296Z","time spent":"4.998638471s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/03/30 10:38:14 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:14.063016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:14.064738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999161342s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-30T10:38:14.064801Z","caller":"traceutil/trace.go:171","msg":"trace[1002794739] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999255914s","start":"2026-03-30T10:38:09.065529Z","end":"2026-03-30T10:38:14.064785Z","steps":["trace[1002794739] 'agreement among raft nodes before linearized reading' (duration: 4.999181543s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:14.064844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:09.065515Z","time spent":"4.999319585s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/03/30 10:38:14 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:14.268155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:09.26976Z","time spent":"4.998389947s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-03-30T10:38:14.564151Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:14.582732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:09.584067Z","time spent":"4.998656212s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/03/30 10:38:14 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:15.064489Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:15.20824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000091397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-30T10:38:15.208338Z","caller":"traceutil/trace.go:171","msg":"trace[1528678552] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00024498s","start":"2026-03-30T10:38:13.20807Z","end":"2026-03-30T10:38:15.208315Z","steps":["trace[1528678552] 'agreement among raft nodes before linearized reading' (duration: 2.000130788s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:15.208386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:13.208046Z","time spent":"2.000331511s","remote":"127.0.0.1:53270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/30 10:38:15 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:15.241346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:10.242924Z","time spent":"4.998415538s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/03/30 10:38:15 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:15.253222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999462397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-30T10:38:15.253285Z","caller":"traceutil/trace.go:171","msg":"trace[298023949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999569539s","start":"2026-03-30T10:38:10.253699Z","end":"2026-03-30T10:38:15.253269Z","steps":["trace[298023949] 'agreement among raft nodes before linearized reading' (duration: 4.999497878s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:15.253325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:10.253678Z","time spent":"4.9996388s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/03/30 10:38:15 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:15.266707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999398775s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-30T10:38:15.266793Z","caller":"traceutil/trace.go:171","msg":"trace[160935050] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999544889s","start":"2026-03-30T10:38:10.267229Z","end":"2026-03-30T10:38:15.266774Z","steps":["trace[160935050] 'agreement among raft nodes before linearized reading' (duration: 4.999436496s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:15.266846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:10.267211Z","time spent":"4.9996239s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/03/30 10:38:15 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:15.565254Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:16.054293Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-03-30T10:38:16.054464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.000634244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-03-30T10:38:16.054542Z","caller":"traceutil/trace.go:171","msg":"trace[111429237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"7.000741246s","start":"2026-03-30T10:38:09.053754Z","end":"2026-03-30T10:38:16.054496Z","steps":["trace[111429237] 'agreement among raft nodes before linearized reading' (duration: 7.000671525s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:16.054592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:09.053734Z","time spent":"7.000847858s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-30T10:38:16.555416Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597367,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:16.695568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:09.694774Z","time spent":"7.000787437s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-03-30T10:38:17.056308Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597367,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:17.208524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999887892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-30T10:38:17.208609Z","caller":"traceutil/trace.go:171","msg":"trace[1393197190] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000034075s","start":"2026-03-30T10:38:15.208555Z","end":"2026-03-30T10:38:17.208589Z","steps":["trace[1393197190] 'agreement among raft nodes before linearized reading' (duration: 1.999933213s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:17.208655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:15.208537Z","time spent":"2.000106477s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/30 10:38:17 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:17.557081Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597367,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:18.057492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597367,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:18.558827Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597367,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:19.007158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998191083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-30T10:38:19.007266Z","caller":"traceutil/trace.go:171","msg":"trace[1757045959] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; }","duration":"4.998381487s","start":"2026-03-30T10:38:14.008865Z","end":"2026-03-30T10:38:19.007247Z","steps":["trace[1757045959] 'agreement among raft nodes before linearized reading' (duration: 4.998225484s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:19.007344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:14.008847Z","time spent":"4.998484159s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":0,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} 2026/03/30 10:38:19 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:19.053278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.980284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-30T10:38:19.053423Z","caller":"traceutil/trace.go:171","msg":"trace[1094752928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"994.134607ms","start":"2026-03-30T10:38:18.059219Z","end":"2026-03-30T10:38:19.053354Z","steps":["trace[1094752928] 'agreement among raft nodes before linearized reading' (duration: 994.015665ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:19.053484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:18.059202Z","time spent":"994.268909ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} 2026/03/30 10:38:19 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:19.059072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597367,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:19.064812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997980395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-30T10:38:19.064862Z","caller":"traceutil/trace.go:171","msg":"trace[297404605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.998033046s","start":"2026-03-30T10:38:16.066816Z","end":"2026-03-30T10:38:19.064849Z","steps":["trace[297404605] 'agreement among raft nodes before linearized reading' (duration: 2.997978525s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:19.064938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:16.066772Z","time spent":"2.998150967s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/03/30 10:38:19 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:19.069484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999004375s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-30T10:38:19.069553Z","caller":"traceutil/trace.go:171","msg":"trace[728689975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"9.999134989s","start":"2026-03-30T10:38:09.070403Z","end":"2026-03-30T10:38:19.069538Z","steps":["trace[728689975] 'agreement among raft nodes before linearized reading' (duration: 9.999037326s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:19.069587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:09.070377Z","time spent":"9.999202809s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/03/30 10:38:19 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:19.215881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000207679s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-30T10:38:19.215982Z","caller":"traceutil/trace.go:171","msg":"trace[226602950] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000358432s","start":"2026-03-30T10:38:17.215593Z","end":"2026-03-30T10:38:19.215951Z","steps":["trace[226602950] 'agreement among raft nodes before linearized reading' (duration: 2.000239179s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:19.21604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:17.215574Z","time spent":"2.000449254s","remote":"127.0.0.1:53270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/30 10:38:19 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:19.268261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.997285796s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-30T10:38:19.268429Z","caller":"traceutil/trace.go:171","msg":"trace[1514246408] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; }","duration":"4.997381287s","start":"2026-03-30T10:38:14.270939Z","end":"2026-03-30T10:38:19.268321Z","steps":["trace[1514246408] 'agreement among raft nodes before linearized reading' (duration: 4.997282106s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:19.268515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:14.270924Z","time spent":"4.997577873s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} 2026/03/30 10:38:19 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:19.559755Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597367,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:19.582371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.997824816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-30T10:38:19.582446Z","caller":"traceutil/trace.go:171","msg":"trace[1580469029] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; }","duration":"4.997917468s","start":"2026-03-30T10:38:14.584511Z","end":"2026-03-30T10:38:19.582428Z","steps":["trace[1580469029] 'agreement among raft nodes before linearized reading' (duration: 4.997821696s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:19.582532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:14.584446Z","time spent":"4.99803481s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":0,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} 2026/03/30 10:38:19 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:19.60258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.002400697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-30T10:38:19.602715Z","caller":"traceutil/trace.go:171","msg":"trace[1425169741] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; }","duration":"7.002531419s","start":"2026-03-30T10:38:12.600113Z","end":"2026-03-30T10:38:19.602645Z","steps":["trace[1425169741] 'agreement among raft nodes before linearized reading' (duration: 7.002437088s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:19.602838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:12.600093Z","time spent":"7.002731274s","remote":"127.0.0.1:53490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":0,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} 2026/03/30 10:38:19 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:20.059897Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597367,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:20.20502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:13.204264Z","time spent":"7.000749027s","remote":"127.0.0.1:53390","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-30T10:38:20.241656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998347906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-30T10:38:20.241753Z","caller":"traceutil/trace.go:171","msg":"trace[1499098942] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; }","duration":"4.998738553s","start":"2026-03-30T10:38:15.242994Z","end":"2026-03-30T10:38:20.241732Z","steps":["trace[1499098942] 'agreement among raft nodes before linearized reading' (duration: 4.998625071s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:20.241848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:15.242981Z","time spent":"4.998853155s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} 2026/03/30 10:38:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:20.253927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998505973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-30T10:38:20.253993Z","caller":"traceutil/trace.go:171","msg":"trace[901786124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.998602485s","start":"2026-03-30T10:38:17.255373Z","end":"2026-03-30T10:38:20.253975Z","steps":["trace[901786124] 'agreement among raft nodes before linearized reading' (duration: 2.998523374s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:20.254035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:17.255362Z","time spent":"2.998665296s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/03/30 10:38:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:20.26646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997627607s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-30T10:38:20.266531Z","caller":"traceutil/trace.go:171","msg":"trace[383001566] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.997713688s","start":"2026-03-30T10:38:17.268802Z","end":"2026-03-30T10:38:20.266516Z","steps":["trace[383001566] 'agreement among raft nodes before linearized reading' (duration: 2.997624337s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:20.266574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:17.268771Z","time spent":"2.99779006s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/03/30 10:38:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:20.536736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:13.53578Z","time spent":"7.000948719s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-03-30T10:38:20.560673Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597367,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:20.643013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999981344s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-30T10:38:20.643099Z","caller":"traceutil/trace.go:171","msg":"trace[187980467] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000121426s","start":"2026-03-30T10:38:18.642962Z","end":"2026-03-30T10:38:20.643083Z","steps":["trace[187980467] 'agreement among raft nodes before linearized reading' (duration: 2.000022074s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:20.64314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:18.642936Z","time spent":"2.000195878s","remote":"127.0.0.1:53270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-30T10:38:21.061484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597367,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:21.216331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00026988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-30T10:38:21.216408Z","caller":"traceutil/trace.go:171","msg":"trace[1807102405] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000402613s","start":"2026-03-30T10:38:19.215987Z","end":"2026-03-30T10:38:21.216389Z","steps":["trace[1807102405] 'agreement among raft nodes before linearized reading' (duration: 2.000309681s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:21.216639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:19.215968Z","time spent":"2.000623837s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-30T10:38:21.562013Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597367,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:22.062858Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597367,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:22.563505Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597367,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:22.824334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:15.824195Z","time spent":"7.000127894s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-03-30T10:38:23.055153Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-03-30T10:38:23.055345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.428472417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-03-30T10:38:23.055338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.372121425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-03-30T10:38:23.055384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.372120945s","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":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-03-30T10:38:23.055414Z","caller":"traceutil/trace.go:171","msg":"trace[1342674650] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; }","duration":"11.42862095s","start":"2026-03-30T10:38:11.62677Z","end":"2026-03-30T10:38:23.055391Z","steps":["trace[1342674650] 'agreement among raft nodes before linearized reading' (duration: 11.428524478s)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:38:23.055426Z","caller":"traceutil/trace.go:171","msg":"trace[989579530] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; }","duration":"11.372253477s","start":"2026-03-30T10:38:11.68315Z","end":"2026-03-30T10:38:23.055403Z","steps":["trace[989579530] 'agreement among raft nodes before linearized reading' (duration: 11.372136595s)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:38:23.055442Z","caller":"traceutil/trace.go:171","msg":"trace[604455056] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; }","duration":"7.372235998s","start":"2026-03-30T10:38:15.683192Z","end":"2026-03-30T10:38:23.055428Z","steps":["trace[604455056] 'agreement among raft nodes before linearized reading' (duration: 7.372158056s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:23.055481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:11.683139Z","time spent":"11.372329919s","remote":"127.0.0.1:53662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":0,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:38:23.055484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:11.626747Z","time spent":"11.428720631s","remote":"127.0.0.1:53464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"warn","ts":"2026-03-30T10:38:23.055503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.070874395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-03-30T10:38:23.055535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.998975475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.124\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-03-30T10:38:23.05556Z","caller":"traceutil/trace.go:171","msg":"trace[13509551] range","detail":"{range_begin:/registry/masterleases/199.19.213.124; range_end:; }","duration":"9.999040066s","start":"2026-03-30T10:38:13.056511Z","end":"2026-03-30T10:38:23.055551Z","steps":["trace[13509551] 'agreement among raft nodes before linearized reading' (duration: 9.999017716s)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:38:23.055563Z","caller":"traceutil/trace.go:171","msg":"trace[445201360] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; }","duration":"12.070976697s","start":"2026-03-30T10:38:10.984572Z","end":"2026-03-30T10:38:23.055549Z","steps":["trace[445201360] 'agreement among raft nodes before linearized reading' (duration: 12.070907326s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:23.055495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:15.683172Z","time spent":"7.372304649s","remote":"127.0.0.1:41186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":0,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:38:23.055588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:13.056488Z","time spent":"9.999094427s","remote":"127.0.0.1:53328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":0,"request content":"key:\"/registry/masterleases/199.19.213.124\" "} {"level":"warn","ts":"2026-03-30T10:38:23.055393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.630629175s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-03-30T10:38:23.055808Z","caller":"traceutil/trace.go:171","msg":"trace[405176528] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; }","duration":"12.631155395s","start":"2026-03-30T10:38:10.424644Z","end":"2026-03-30T10:38:23.0558Z","steps":["trace[405176528] 'agreement among raft nodes before linearized reading' (duration: 12.630676296s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:23.055798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.855063499s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-03-30T10:38:23.05584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:10.42462Z","time spent":"12.631211296s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":0,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-03-30T10:38:23.055599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:10.984552Z","time spent":"12.071037758s","remote":"127.0.0.1:53472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":0,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"info","ts":"2026-03-30T10:38:23.055866Z","caller":"traceutil/trace.go:171","msg":"trace[423886711] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; }","duration":"11.85516431s","start":"2026-03-30T10:38:11.200679Z","end":"2026-03-30T10:38:23.055843Z","steps":["trace[423886711] 'agreement among raft nodes before linearized reading' (duration: 11.855096909s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:23.055939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:11.200658Z","time spent":"11.855264063s","remote":"127.0.0.1:53512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":0,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:38:23.05559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.781447667s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bzkx7\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-03-30T10:38:23.05611Z","caller":"traceutil/trace.go:171","msg":"trace[2004432524] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bzkx7; range_end:; }","duration":"12.781992938s","start":"2026-03-30T10:38:10.274098Z","end":"2026-03-30T10:38:23.056091Z","steps":["trace[2004432524] 'agreement among raft nodes before linearized reading' (duration: 12.781467177s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:23.055626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.655379239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-03-30T10:38:23.056269Z","caller":"traceutil/trace.go:171","msg":"trace[398212626] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; }","duration":"13.656182285s","start":"2026-03-30T10:38:09.400072Z","end":"2026-03-30T10:38:23.056255Z","steps":["trace[398212626] 'agreement among raft nodes before linearized reading' (duration: 13.65541862s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:23.055666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.367060589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-03-30T10:38:23.056223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:10.274086Z","time spent":"12.78212082s","remote":"127.0.0.1:53490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":0,"request content":"key:\"/registry/pods/openstack/keepalived-bzkx7\" "} {"level":"warn","ts":"2026-03-30T10:38:23.055606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.943658391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-03-30T10:38:23.056383Z","caller":"traceutil/trace.go:171","msg":"trace[1390243796] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; }","duration":"13.944542909s","start":"2026-03-30T10:38:09.111829Z","end":"2026-03-30T10:38:23.056372Z","steps":["trace[1390243796] 'agreement among raft nodes before linearized reading' (duration: 13.943674602s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:23.055701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.152860665s","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":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-03-30T10:38:23.056665Z","caller":"traceutil/trace.go:171","msg":"trace[548426586] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; }","duration":"10.153862995s","start":"2026-03-30T10:38:12.902779Z","end":"2026-03-30T10:38:23.056642Z","steps":["trace[548426586] 'agreement among raft nodes before linearized reading' (duration: 10.152900646s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:23.056738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:12.902763Z","time spent":"10.153948746s","remote":"127.0.0.1:54536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":0,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:38:23.055718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.231028556s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-03-30T10:38:23.056806Z","caller":"traceutil/trace.go:171","msg":"trace[1894701574] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; }","duration":"7.232184298s","start":"2026-03-30T10:38:15.824607Z","end":"2026-03-30T10:38:23.056792Z","steps":["trace[1894701574] 'agreement among raft nodes before linearized reading' (duration: 7.231063427s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:23.056854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:15.824582Z","time spent":"7.232256929s","remote":"127.0.0.1:53764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":0,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:38:23.056848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:09.111817Z","time spent":"13.944591959s","remote":"127.0.0.1:53644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":0,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:38:23.0557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.326114821s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-03-30T10:38:23.05691Z","caller":"traceutil/trace.go:171","msg":"trace[1466583566] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; }","duration":"7.367855975s","start":"2026-03-30T10:38:15.68853Z","end":"2026-03-30T10:38:23.056386Z","steps":["trace[1466583566] 'agreement among raft nodes before linearized reading' (duration: 7.36710295s)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:38:23.057074Z","caller":"traceutil/trace.go:171","msg":"trace[688011714] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; }","duration":"10.327569968s","start":"2026-03-30T10:38:12.729489Z","end":"2026-03-30T10:38:23.057059Z","steps":["trace[688011714] 'agreement among raft nodes before linearized reading' (duration: 10.326124411s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:23.057128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:12.729479Z","time spent":"10.32763098s","remote":"127.0.0.1:37490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":0,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:38:23.057183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:15.688504Z","time spent":"7.368575179s","remote":"127.0.0.1:54714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":0,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:38:23.056333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:09.400052Z","time spent":"13.656268786s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":0,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-03-30T10:38:23.22397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000066666s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-30T10:38:23.224051Z","caller":"traceutil/trace.go:171","msg":"trace[1957887375] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000185579s","start":"2026-03-30T10:38:21.223848Z","end":"2026-03-30T10:38:23.224033Z","steps":["trace[1957887375] 'agreement among raft nodes before linearized reading' (duration: 2.000084017s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:23.224097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:21.223834Z","time spent":"2.00025467s","remote":"127.0.0.1:53270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/30 10:38:23 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:23.556026Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597377,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:23.700299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:16.700063Z","time spent":"7.000230066s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-03-30T10:38:24.057053Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055597377,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T10:38:24.065888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:19.06737Z","time spent":"4.998508439s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/03/30 10:38:24 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-30T10:38:24.46512Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"15.456676249s","expected-duration":"1s"} {"level":"info","ts":"2026-03-30T10:38:24.586723Z","caller":"traceutil/trace.go:171","msg":"trace[681305118] transaction","detail":"{read_only:false; response_revision:6505; number_of_response:1; }","duration":"5.530983414s","start":"2026-03-30T10:38:19.055711Z","end":"2026-03-30T10:38:24.586694Z","steps":["trace[681305118] 'process raft request' (duration: 5.530668209s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.586881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:19.055683Z","time spent":"5.531111217s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":413,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T10:38:24.587434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:20.206339Z","time spent":"4.381093452s","remote":"127.0.0.1:53390","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-30T10:38:24.587589Z","caller":"traceutil/trace.go:171","msg":"trace[2010531078] transaction","detail":"{read_only:false; response_revision:6507; number_of_response:1; }","duration":"5.516552522s","start":"2026-03-30T10:38:19.071025Z","end":"2026-03-30T10:38:24.587578Z","steps":["trace[2010531078] 'process raft request' (duration: 5.516174455s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.587659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:19.071007Z","time spent":"5.516610344s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":409,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T10:38:24.587679Z","caller":"traceutil/trace.go:171","msg":"trace[233803497] linearizableReadLoop","detail":"{readStateIndex:6743; appliedIndex:6727; }","duration":"1.532409963s","start":"2026-03-30T10:38:23.05525Z","end":"2026-03-30T10:38:24.58766Z","steps":["trace[233803497] 'read index received' (duration: 1.410396613s)","trace[233803497] 'applied index is now lower than readState.Index' (duration: 122.01193ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:38:24.587862Z","caller":"traceutil/trace.go:171","msg":"trace[769841591] transaction","detail":"{read_only:false; response_revision:6508; number_of_response:1; }","duration":"4.31888571s","start":"2026-03-30T10:38:20.268965Z","end":"2026-03-30T10:38:24.587851Z","steps":["trace[769841591] 'process raft request' (duration: 4.318419091s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.587959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.063261252s","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":"warn","ts":"2026-03-30T10:38:24.587979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:20.268939Z","time spent":"4.318952812s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":456,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T10:38:24.587999Z","caller":"traceutil/trace.go:171","msg":"trace[561354660] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6508; }","duration":"4.063303313s","start":"2026-03-30T10:38:20.524683Z","end":"2026-03-30T10:38:24.587987Z","steps":["trace[561354660] 'agreement among raft nodes before linearized reading' (duration: 4.063201691s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.588028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:20.524627Z","time spent":"4.063391494s","remote":"127.0.0.1:41192","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":"info","ts":"2026-03-30T10:38:24.588166Z","caller":"traceutil/trace.go:171","msg":"trace[1278656537] transaction","detail":"{read_only:false; number_of_response:1; response_revision:6508; }","duration":"1.759983161s","start":"2026-03-30T10:38:22.828175Z","end":"2026-03-30T10:38:24.588158Z","steps":["trace[1278656537] 'process raft request' (duration: 1.75939083s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.588217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:22.828154Z","time spent":"1.760035143s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":721,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T10:38:24.588292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.631237977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:38:24.588321Z","caller":"traceutil/trace.go:171","msg":"trace[556907749] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:6508; }","duration":"4.631306659s","start":"2026-03-30T10:38:19.957005Z","end":"2026-03-30T10:38:24.588312Z","steps":["trace[556907749] 'agreement among raft nodes before linearized reading' (duration: 4.631244328s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.588358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.36867264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7111"} {"level":"info","ts":"2026-03-30T10:38:24.588378Z","caller":"traceutil/trace.go:171","msg":"trace[305095444] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:6508; }","duration":"3.368721492s","start":"2026-03-30T10:38:21.21965Z","end":"2026-03-30T10:38:24.588372Z","steps":["trace[305095444] 'agreement among raft nodes before linearized reading' (duration: 3.368669951s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.588397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:21.219633Z","time spent":"3.368759452s","remote":"127.0.0.1:53490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7134,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-03-30T10:38:24.588379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.284138249s","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-03-30T10:38:24.58844Z","caller":"traceutil/trace.go:171","msg":"trace[649003190] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:6508; }","duration":"2.284253272s","start":"2026-03-30T10:38:22.304172Z","end":"2026-03-30T10:38:24.588425Z","steps":["trace[649003190] 'agreement among raft nodes before linearized reading' (duration: 2.28412767s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.588474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:22.304151Z","time spent":"2.284316323s","remote":"127.0.0.1:41080","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-03-30T10:38:24.588344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:19.956985Z","time spent":"4.631353109s","remote":"127.0.0.1:54576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:38:24.588577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.890161675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-30T10:38:24.588697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.469069568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:38:24.588668Z","caller":"traceutil/trace.go:171","msg":"trace[1276935785] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:6508; }","duration":"1.890270637s","start":"2026-03-30T10:38:22.698373Z","end":"2026-03-30T10:38:24.588644Z","steps":["trace[1276935785] 'agreement among raft nodes before linearized reading' (duration: 1.890100814s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.58871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.920551867s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:38:24.588722Z","caller":"traceutil/trace.go:171","msg":"trace[1602918911] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:6508; }","duration":"5.469138479s","start":"2026-03-30T10:38:19.119575Z","end":"2026-03-30T10:38:24.588714Z","steps":["trace[1602918911] 'agreement among raft nodes before linearized reading' (duration: 5.469094198s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.588742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:19.119559Z","time spent":"5.469176609s","remote":"127.0.0.1:54562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-03-30T10:38:24.58874Z","caller":"traceutil/trace.go:171","msg":"trace[838939715] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:6508; }","duration":"1.920617147s","start":"2026-03-30T10:38:22.668112Z","end":"2026-03-30T10:38:24.588729Z","steps":["trace[838939715] 'agreement among raft nodes before linearized reading' (duration: 1.920572296s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.588748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:22.698291Z","time spent":"1.890439789s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:38:24.588764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:22.668091Z","time spent":"1.920668189s","remote":"127.0.0.1:54770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:38:24.588857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.995294614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-30T10:38:24.588875Z","caller":"traceutil/trace.go:171","msg":"trace[68730097] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:6508; }","duration":"6.995353216s","start":"2026-03-30T10:38:17.593516Z","end":"2026-03-30T10:38:24.58887Z","steps":["trace[68730097] 'agreement among raft nodes before linearized reading' (duration: 6.995309764s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.588892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:17.593482Z","time spent":"6.995405196s","remote":"127.0.0.1:53730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:38:24.588944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.12067834s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:38:24.588977Z","caller":"traceutil/trace.go:171","msg":"trace[1811508078] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:6508; }","duration":"2.120741631s","start":"2026-03-30T10:38:22.468226Z","end":"2026-03-30T10:38:24.588968Z","steps":["trace[1811508078] 'agreement among raft nodes before linearized reading' (duration: 2.12068943s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.589001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.185854075s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:38:24.589005Z","caller":"traceutil/trace.go:171","msg":"trace[1301459587] transaction","detail":"{read_only:false; number_of_response:1; response_revision:6508; }","duration":"885.49537ms","start":"2026-03-30T10:38:23.703493Z","end":"2026-03-30T10:38:24.588988Z","steps":["trace[1301459587] 'process raft request' (duration: 884.129864ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.58871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.05042449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19521"} {"level":"warn","ts":"2026-03-30T10:38:24.588618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.048899871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-30T10:38:24.589067Z","caller":"traceutil/trace.go:171","msg":"trace[1541361976] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:6508; }","duration":"4.050806438s","start":"2026-03-30T10:38:20.538253Z","end":"2026-03-30T10:38:24.58906Z","steps":["trace[1541361976] 'agreement among raft nodes before linearized reading' (duration: 4.05038085s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.589081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.939092163s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-30T10:38:24.589081Z","caller":"traceutil/trace.go:171","msg":"trace[1737233284] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:1; response_revision:6508; }","duration":"4.04938149s","start":"2026-03-30T10:38:20.539688Z","end":"2026-03-30T10:38:24.58907Z","steps":["trace[1737233284] 'agreement among raft nodes before linearized reading' (duration: 4.04883971s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.58909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:20.538244Z","time spent":"4.050839059s","remote":"127.0.0.1:53478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":19544,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"info","ts":"2026-03-30T10:38:24.589099Z","caller":"traceutil/trace.go:171","msg":"trace[721665034] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:6508; }","duration":"7.939146613s","start":"2026-03-30T10:38:16.649946Z","end":"2026-03-30T10:38:24.589093Z","steps":["trace[721665034] 'agreement among raft nodes before linearized reading' (duration: 7.939086042s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.58911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:20.539677Z","time spent":"4.049424451s","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":513,"request content":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" "} {"level":"warn","ts":"2026-03-30T10:38:24.589122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:16.649927Z","time spent":"7.939186104s","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":41,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:38:24.589115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:23.703477Z","time spent":"885.568961ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":570,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T10:38:24.589247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.532173759s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.124\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-30T10:38:24.589245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.824951116s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:38:24.589264Z","caller":"traceutil/trace.go:171","msg":"trace[552793452] range","detail":"{range_begin:/registry/masterleases/199.19.213.124; range_end:; response_count:0; response_revision:6508; }","duration":"1.532226199s","start":"2026-03-30T10:38:23.057032Z","end":"2026-03-30T10:38:24.589259Z","steps":["trace[552793452] 'agreement among raft nodes before linearized reading' (duration: 1.532190228s)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:38:24.589021Z","caller":"traceutil/trace.go:171","msg":"trace[1263475371] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:6508; }","duration":"7.185908566s","start":"2026-03-30T10:38:17.403106Z","end":"2026-03-30T10:38:24.589014Z","steps":["trace[1263475371] 'agreement among raft nodes before linearized reading' (duration: 7.185873506s)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:38:24.589277Z","caller":"traceutil/trace.go:171","msg":"trace[1690155320] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:6508; }","duration":"1.825013357s","start":"2026-03-30T10:38:22.764255Z","end":"2026-03-30T10:38:24.589268Z","steps":["trace[1690155320] 'agreement among raft nodes before linearized reading' (duration: 1.824951976s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.589294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:17.403089Z","time spent":"7.186196281s","remote":"127.0.0.1:37868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:38:24.589304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:22.764241Z","time spent":"1.825056169s","remote":"127.0.0.1:53534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:38:24.589001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:22.468209Z","time spent":"2.120785701s","remote":"127.0.0.1:54630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:38:24.589556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.476653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:38:24.589579Z","caller":"traceutil/trace.go:171","msg":"trace[1760963999] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:6508; }","duration":"360.552466ms","start":"2026-03-30T10:38:24.229019Z","end":"2026-03-30T10:38:24.589572Z","steps":["trace[1760963999] 'agreement among raft nodes before linearized reading' (duration: 360.508535ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.589607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:24.228992Z","time spent":"360.608877ms","remote":"127.0.0.1:53520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:38:24.589282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:23.057013Z","time spent":"1.53226575s","remote":"127.0.0.1:53328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.19.213.124\" "} {"level":"warn","ts":"2026-03-30T10:38:24.58976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.171101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-30T10:38:24.589785Z","caller":"traceutil/trace.go:171","msg":"trace[597283400] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:6508; }","duration":"392.253322ms","start":"2026-03-30T10:38:24.197522Z","end":"2026-03-30T10:38:24.589776Z","steps":["trace[597283400] 'agreement among raft nodes before linearized reading' (duration: 392.174841ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.589803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:24.197496Z","time spent":"392.301704ms","remote":"127.0.0.1:53660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":76,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:38:24.589959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.951063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:38:24.589982Z","caller":"traceutil/trace.go:171","msg":"trace[1326311346] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:6509; }","duration":"802.015166ms","start":"2026-03-30T10:38:23.78796Z","end":"2026-03-30T10:38:24.589975Z","steps":["trace[1326311346] 'agreement among raft nodes before linearized reading' (duration: 801.931624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.589998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:23.787937Z","time spent":"802.055547ms","remote":"127.0.0.1:53678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:38:24.590008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025154841s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:38:24.590058Z","caller":"traceutil/trace.go:171","msg":"trace[1884918952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6509; }","duration":"1.025225992s","start":"2026-03-30T10:38:23.564815Z","end":"2026-03-30T10:38:24.590041Z","steps":["trace[1884918952] 'agreement among raft nodes before linearized reading' (duration: 1.02511031s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.590093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:23.5648Z","time spent":"1.025285454s","remote":"127.0.0.1:53270","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-03-30T10:38:24.590228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.365874572s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:38:24.590252Z","caller":"traceutil/trace.go:171","msg":"trace[1181755220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6509; }","duration":"1.365926544s","start":"2026-03-30T10:38:23.224319Z","end":"2026-03-30T10:38:24.590246Z","steps":["trace[1181755220] 'agreement among raft nodes before linearized reading' (duration: 1.365887913s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:38:24.590268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:38:23.224303Z","time spent":"1.365961835s","remote":"127.0.0.1:53280","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-03-30T10:40:12.843228Z","caller":"traceutil/trace.go:171","msg":"trace[2039122076] transaction","detail":"{read_only:false; response_revision:7258; number_of_response:1; }","duration":"161.289587ms","start":"2026-03-30T10:40:12.681918Z","end":"2026-03-30T10:40:12.843208Z","steps":["trace[2039122076] 'process raft request' (duration: 161.156984ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:40:17.726038Z","caller":"traceutil/trace.go:171","msg":"trace[1064926589] transaction","detail":"{read_only:false; response_revision:7285; number_of_response:1; }","duration":"225.796714ms","start":"2026-03-30T10:40:17.500213Z","end":"2026-03-30T10:40:17.72601Z","steps":["trace[1064926589] 'process raft request' (duration: 225.596921ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:40:17.821335Z","caller":"traceutil/trace.go:171","msg":"trace[1655604880] linearizableReadLoop","detail":"{readStateIndex:7551; appliedIndex:7549; }","duration":"145.171922ms","start":"2026-03-30T10:40:17.676138Z","end":"2026-03-30T10:40:17.82131Z","steps":["trace[1655604880] 'read index received' (duration: 49.7121ms)","trace[1655604880] 'applied index is now lower than readState.Index' (duration: 95.458642ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:40:17.82144Z","caller":"traceutil/trace.go:171","msg":"trace[1077170630] transaction","detail":"{read_only:false; response_revision:7286; number_of_response:1; }","duration":"177.643055ms","start":"2026-03-30T10:40:17.643763Z","end":"2026-03-30T10:40:17.821406Z","steps":["trace[1077170630] 'process raft request' (duration: 177.36248ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:40:17.821618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.470607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bzkx7\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-30T10:40:17.821669Z","caller":"traceutil/trace.go:171","msg":"trace[1878237805] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bzkx7; range_end:; response_count:1; response_revision:7286; }","duration":"145.567399ms","start":"2026-03-30T10:40:17.676086Z","end":"2026-03-30T10:40:17.821653Z","steps":["trace[1878237805] 'agreement among raft nodes before linearized reading' (duration: 145.393056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:40:17.821865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.905363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-30T10:40:17.821994Z","caller":"traceutil/trace.go:171","msg":"trace[575790967] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:7286; }","duration":"122.090906ms","start":"2026-03-30T10:40:17.69988Z","end":"2026-03-30T10:40:17.821971Z","steps":["trace[575790967] 'agreement among raft nodes before linearized reading' (duration: 121.715738ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:42:10.125249Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6151} {"level":"info","ts":"2026-03-30T10:42:10.226369Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6151,"took":"98.885965ms","hash":2149556583,"current-db-size-bytes":57335808,"current-db-size":"57 MB","current-db-size-in-use-bytes":21766144,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-03-30T10:42:10.226453Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2149556583,"revision":6151,"compact-revision":3043} {"level":"info","ts":"2026-03-30T10:42:30.607744Z","caller":"traceutil/trace.go:171","msg":"trace[608047451] transaction","detail":"{read_only:false; response_revision:8383; number_of_response:1; }","duration":"378.606841ms","start":"2026-03-30T10:42:30.229113Z","end":"2026-03-30T10:42:30.60772Z","steps":["trace[608047451] 'process raft request' (duration: 378.453637ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:42:30.607916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:42:30.229092Z","time spent":"378.731993ms","remote":"127.0.0.1:53568","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-03-30T10:42:30.826984Z","caller":"traceutil/trace.go:171","msg":"trace[370299674] linearizableReadLoop","detail":"{readStateIndex:8686; appliedIndex:8684; }","duration":"226.776217ms","start":"2026-03-30T10:42:30.600178Z","end":"2026-03-30T10:42:30.826954Z","steps":["trace[370299674] 'read index received' (duration: 7.374739ms)","trace[370299674] 'applied index is now lower than readState.Index' (duration: 219.400198ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:42:30.827134Z","caller":"traceutil/trace.go:171","msg":"trace[63399419] transaction","detail":"{read_only:false; response_revision:8384; number_of_response:1; }","duration":"252.816517ms","start":"2026-03-30T10:42:30.574307Z","end":"2026-03-30T10:42:30.827123Z","steps":["trace[63399419] 'process raft request' (duration: 251.470922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:42:30.827308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.875613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bzkx7\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-03-30T10:42:30.827361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.167474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-30T10:42:30.827418Z","caller":"traceutil/trace.go:171","msg":"trace[1732136149] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bzkx7; range_end:; response_count:1; response_revision:8384; }","duration":"214.057096ms","start":"2026-03-30T10:42:30.613341Z","end":"2026-03-30T10:42:30.827398Z","steps":["trace[1732136149] 'agreement among raft nodes before linearized reading' (duration: 213.806032ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:42:30.82744Z","caller":"traceutil/trace.go:171","msg":"trace[124623539] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8384; }","duration":"227.287946ms","start":"2026-03-30T10:42:30.600133Z","end":"2026-03-30T10:42:30.827421Z","steps":["trace[124623539] 'agreement among raft nodes before linearized reading' (duration: 227.085582ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:42:41.574513Z","caller":"traceutil/trace.go:171","msg":"trace[1887038892] transaction","detail":"{read_only:false; response_revision:8443; number_of_response:1; }","duration":"117.318752ms","start":"2026-03-30T10:42:41.457168Z","end":"2026-03-30T10:42:41.574487Z","steps":["trace[1887038892] 'process raft request' (duration: 117.137859ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:42:41.627524Z","caller":"traceutil/trace.go:171","msg":"trace[108360081] transaction","detail":"{read_only:false; response_revision:8444; number_of_response:1; }","duration":"135.044266ms","start":"2026-03-30T10:42:41.492449Z","end":"2026-03-30T10:42:41.627493Z","steps":["trace[108360081] 'process raft request' (duration: 132.931917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:42:41.963174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.349221ms","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":"warn","ts":"2026-03-30T10:42:41.963192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.470574ms","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-03-30T10:42:41.963268Z","caller":"traceutil/trace.go:171","msg":"trace[51935927] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8444; }","duration":"233.510285ms","start":"2026-03-30T10:42:41.729738Z","end":"2026-03-30T10:42:41.963248Z","steps":["trace[51935927] 'count revisions from in-memory index tree' (duration: 233.25101ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:42:41.96329Z","caller":"traceutil/trace.go:171","msg":"trace[616996596] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8444; }","duration":"189.621937ms","start":"2026-03-30T10:42:41.773646Z","end":"2026-03-30T10:42:41.963268Z","steps":["trace[616996596] 'range keys from in-memory index tree' (duration: 189.235069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:42:43.083382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.067717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-03-30T10:42:43.083447Z","caller":"traceutil/trace.go:171","msg":"trace[1745668271] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8446; }","duration":"232.182349ms","start":"2026-03-30T10:42:42.85125Z","end":"2026-03-30T10:42:43.083432Z","steps":["trace[1745668271] 'range keys from in-memory index tree' (duration: 231.893384ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:42:43.155765Z","caller":"traceutil/trace.go:171","msg":"trace[287616148] linearizableReadLoop","detail":"{readStateIndex:8752; appliedIndex:8751; }","duration":"271.275337ms","start":"2026-03-30T10:42:42.884464Z","end":"2026-03-30T10:42:43.155739Z","steps":["trace[287616148] 'read index received' (duration: 271.041871ms)","trace[287616148] 'applied index is now lower than readState.Index' (duration: 232.516µs)"],"step_count":2} {"level":"warn","ts":"2026-03-30T10:42:43.156028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.545831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-30T10:42:43.1561Z","caller":"traceutil/trace.go:171","msg":"trace[373076002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8447; }","duration":"271.653134ms","start":"2026-03-30T10:42:42.884428Z","end":"2026-03-30T10:42:43.156081Z","steps":["trace[373076002] 'agreement among raft nodes before linearized reading' (duration: 271.45252ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:42:43.156205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.856848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bzkx7\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-30T10:42:43.156319Z","caller":"traceutil/trace.go:171","msg":"trace[1852894684] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bzkx7; range_end:; response_count:1; response_revision:8447; }","duration":"262.027622ms","start":"2026-03-30T10:42:42.894277Z","end":"2026-03-30T10:42:43.156305Z","steps":["trace[1852894684] 'agreement among raft nodes before linearized reading' (duration: 261.680686ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:42:43.156568Z","caller":"traceutil/trace.go:171","msg":"trace[538279273] transaction","detail":"{read_only:false; response_revision:8447; number_of_response:1; }","duration":"293.371554ms","start":"2026-03-30T10:42:42.862433Z","end":"2026-03-30T10:42:43.155804Z","steps":["trace[538279273] 'process raft request' (duration: 293.134958ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:42:44.901795Z","caller":"traceutil/trace.go:171","msg":"trace[1742208838] transaction","detail":"{read_only:false; response_revision:8457; number_of_response:1; }","duration":"200.533943ms","start":"2026-03-30T10:42:44.701236Z","end":"2026-03-30T10:42:44.901769Z","steps":["trace[1742208838] 'process raft request' (duration: 200.38814ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:42:44.902135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.687614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:42:44.902223Z","caller":"traceutil/trace.go:171","msg":"trace[30126522] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8457; }","duration":"140.872467ms","start":"2026-03-30T10:42:44.761341Z","end":"2026-03-30T10:42:44.902213Z","steps":["trace[30126522] 'agreement among raft nodes before linearized reading' (duration: 140.742375ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:42:44.90204Z","caller":"traceutil/trace.go:171","msg":"trace[1100327522] linearizableReadLoop","detail":"{readStateIndex:8763; appliedIndex:8763; }","duration":"140.583452ms","start":"2026-03-30T10:42:44.761435Z","end":"2026-03-30T10:42:44.902018Z","steps":["trace[1100327522] 'read index received' (duration: 140.557622ms)","trace[1100327522] 'applied index is now lower than readState.Index' (duration: 24.34µs)"],"step_count":2} {"level":"warn","ts":"2026-03-30T10:42:44.902545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.877477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:42:44.902581Z","caller":"traceutil/trace.go:171","msg":"trace[473220367] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8457; }","duration":"140.943578ms","start":"2026-03-30T10:42:44.761628Z","end":"2026-03-30T10:42:44.902571Z","steps":["trace[473220367] 'agreement among raft nodes before linearized reading' (duration: 140.885856ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:42:44.902622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.245085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:42:44.902707Z","caller":"traceutil/trace.go:171","msg":"trace[637892664] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8457; }","duration":"140.364018ms","start":"2026-03-30T10:42:44.762325Z","end":"2026-03-30T10:42:44.902689Z","steps":["trace[637892664] 'agreement among raft nodes before linearized reading' (duration: 140.221545ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:43:12.539385Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2003711775055603361,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-30T10:43:12.662048Z","caller":"traceutil/trace.go:171","msg":"trace[1528643052] linearizableReadLoop","detail":"{readStateIndex:8972; appliedIndex:8971; }","duration":"622.889499ms","start":"2026-03-30T10:43:12.039139Z","end":"2026-03-30T10:43:12.662029Z","steps":["trace[1528643052] 'read index received' (duration: 622.669845ms)","trace[1528643052] 'applied index is now lower than readState.Index' (duration: 218.694µs)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:43:12.662116Z","caller":"traceutil/trace.go:171","msg":"trace[1907459116] transaction","detail":"{read_only:false; response_revision:8659; number_of_response:1; }","duration":"656.853883ms","start":"2026-03-30T10:43:12.005221Z","end":"2026-03-30T10:43:12.662075Z","steps":["trace[1907459116] 'process raft request' (duration: 656.657389ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:43:12.662208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.058203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-30T10:43:12.66224Z","caller":"traceutil/trace.go:171","msg":"trace[1799486306] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8659; }","duration":"623.158445ms","start":"2026-03-30T10:43:12.039072Z","end":"2026-03-30T10:43:12.662231Z","steps":["trace[1799486306] 'agreement among raft nodes before linearized reading' (duration: 623.071833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:43:12.662267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:43:12.03905Z","time spent":"623.210656ms","remote":"127.0.0.1:53724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-30T10:43:12.662422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.533935ms","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-03-30T10:43:12.662518Z","caller":"traceutil/trace.go:171","msg":"trace[170907473] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8659; }","duration":"467.686857ms","start":"2026-03-30T10:43:12.194816Z","end":"2026-03-30T10:43:12.662503Z","steps":["trace[170907473] 'agreement among raft nodes before linearized reading' (duration: 467.411552ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:43:12.662582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:43:12.194787Z","time spent":"467.783289ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-30T10:43:12.662694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:43:12.005196Z","time spent":"656.985385ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T10:43:25.209783Z","caller":"traceutil/trace.go:171","msg":"trace[467363597] transaction","detail":"{read_only:false; response_revision:8770; number_of_response:1; }","duration":"109.730303ms","start":"2026-03-30T10:43:25.100026Z","end":"2026-03-30T10:43:25.209756Z","steps":["trace[467363597] 'process raft request' (duration: 106.3996ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:43:25.307845Z","caller":"traceutil/trace.go:171","msg":"trace[250663677] transaction","detail":"{read_only:false; response_revision:8772; number_of_response:1; }","duration":"172.593541ms","start":"2026-03-30T10:43:25.135226Z","end":"2026-03-30T10:43:25.307819Z","steps":["trace[250663677] 'process raft request' (duration: 172.469799ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:43:25.307963Z","caller":"traceutil/trace.go:171","msg":"trace[906434390] transaction","detail":"{read_only:false; response_revision:8771; number_of_response:1; }","duration":"182.163772ms","start":"2026-03-30T10:43:25.125752Z","end":"2026-03-30T10:43:25.307916Z","steps":["trace[906434390] 'process raft request' (duration: 181.817216ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:43:25.308089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.276785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:43:25.308211Z","caller":"traceutil/trace.go:171","msg":"trace[237053415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8772; }","duration":"109.613132ms","start":"2026-03-30T10:43:25.198576Z","end":"2026-03-30T10:43:25.308189Z","steps":["trace[237053415] 'agreement among raft nodes before linearized reading' (duration: 109.290715ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:43:25.501495Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002385.wal"} {"level":"info","ts":"2026-03-30T10:43:25.502739Z","caller":"traceutil/trace.go:171","msg":"trace[1042248201] transaction","detail":"{read_only:false; response_revision:8773; number_of_response:1; }","duration":"191.854355ms","start":"2026-03-30T10:43:25.31086Z","end":"2026-03-30T10:43:25.502715Z","steps":["trace[1042248201] 'process raft request' (duration: 191.09761ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:43:25.51394Z","caller":"traceutil/trace.go:171","msg":"trace[469733499] transaction","detail":"{read_only:false; response_revision:8774; number_of_response:1; }","duration":"194.931184ms","start":"2026-03-30T10:43:25.31898Z","end":"2026-03-30T10:43:25.513911Z","steps":["trace[469733499] 'process raft request' (duration: 194.74658ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:43:25.513998Z","caller":"traceutil/trace.go:171","msg":"trace[2101088267] linearizableReadLoop","detail":"{readStateIndex:9094; appliedIndex:9091; }","duration":"144.128564ms","start":"2026-03-30T10:43:25.369848Z","end":"2026-03-30T10:43:25.513976Z","steps":["trace[2101088267] 'read index received' (duration: 131.864161ms)","trace[2101088267] 'applied index is now lower than readState.Index' (duration: 12.263083ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T10:43:25.514179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.322937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-30T10:43:25.514222Z","caller":"traceutil/trace.go:171","msg":"trace[1346202999] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8775; }","duration":"144.409389ms","start":"2026-03-30T10:43:25.369802Z","end":"2026-03-30T10:43:25.514212Z","steps":["trace[1346202999] 'agreement among raft nodes before linearized reading' (duration: 144.259446ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:43:25.514178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.836964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T10:43:25.514207Z","caller":"traceutil/trace.go:171","msg":"trace[1137142370] transaction","detail":"{read_only:false; response_revision:8775; number_of_response:1; }","duration":"195.161669ms","start":"2026-03-30T10:43:25.318974Z","end":"2026-03-30T10:43:25.514136Z","steps":["trace[1137142370] 'process raft request' (duration: 194.868892ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:43:25.514423Z","caller":"traceutil/trace.go:171","msg":"trace[968509830] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8775; }","duration":"103.137869ms","start":"2026-03-30T10:43:25.411269Z","end":"2026-03-30T10:43:25.514406Z","steps":["trace[968509830] 'agreement among raft nodes before linearized reading' (duration: 102.823974ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:43:26.099836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.282883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T10:43:26.099936Z","caller":"traceutil/trace.go:171","msg":"trace[1628649529] linearizableReadLoop","detail":"{readStateIndex:9101; appliedIndex:9100; }","duration":"291.652671ms","start":"2026-03-30T10:43:25.808267Z","end":"2026-03-30T10:43:26.099919Z","steps":["trace[1628649529] 'read index received' (duration: 99.207165ms)","trace[1628649529] 'applied index is now lower than readState.Index' (duration: 192.443866ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:43:26.100087Z","caller":"traceutil/trace.go:171","msg":"trace[2070411080] transaction","detail":"{read_only:false; response_revision:8782; number_of_response:1; }","duration":"299.385887ms","start":"2026-03-30T10:43:25.800681Z","end":"2026-03-30T10:43:26.100067Z","steps":["trace[2070411080] 'process raft request' (duration: 106.792609ms)","trace[2070411080] 'compare' (duration: 191.966017ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T10:43:26.105739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.44715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210989"} {"level":"info","ts":"2026-03-30T10:43:26.105832Z","caller":"traceutil/trace.go:171","msg":"trace[1292878664] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8782; }","duration":"297.582793ms","start":"2026-03-30T10:43:25.80823Z","end":"2026-03-30T10:43:26.105813Z","steps":["trace[1292878664] 'agreement among raft nodes before linearized reading' (duration: 291.734033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:44:25.012841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.376858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414545"} {"level":"info","ts":"2026-03-30T10:44:25.012934Z","caller":"traceutil/trace.go:171","msg":"trace[517659068] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:9416; }","duration":"154.51682ms","start":"2026-03-30T10:44:24.858399Z","end":"2026-03-30T10:44:25.012916Z","steps":["trace[517659068] 'range keys from in-memory index tree' (duration: 154.101073ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:44:38.152507Z","caller":"traceutil/trace.go:171","msg":"trace[915652353] linearizableReadLoop","detail":"{readStateIndex:9839; appliedIndex:9838; }","duration":"282.001568ms","start":"2026-03-30T10:44:37.870483Z","end":"2026-03-30T10:44:38.152484Z","steps":["trace[915652353] 'read index received' (duration: 281.841115ms)","trace[915652353] 'applied index is now lower than readState.Index' (duration: 159.273µs)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:44:38.152589Z","caller":"traceutil/trace.go:171","msg":"trace[1322568752] transaction","detail":"{read_only:false; response_revision:9498; number_of_response:1; }","duration":"367.940611ms","start":"2026-03-30T10:44:37.784617Z","end":"2026-03-30T10:44:38.152558Z","steps":["trace[1322568752] 'process raft request' (duration: 367.716047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:44:38.152711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.216612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-30T10:44:38.152753Z","caller":"traceutil/trace.go:171","msg":"trace[879610010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9498; }","duration":"282.310914ms","start":"2026-03-30T10:44:37.870433Z","end":"2026-03-30T10:44:38.152744Z","steps":["trace[879610010] 'agreement among raft nodes before linearized reading' (duration: 282.169061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:44:38.152747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:44:37.784588Z","time spent":"368.075424ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T10:44:38.179091Z","caller":"traceutil/trace.go:171","msg":"trace[1802089314] transaction","detail":"{read_only:false; response_revision:9499; number_of_response:1; }","duration":"191.517009ms","start":"2026-03-30T10:44:37.987542Z","end":"2026-03-30T10:44:38.179059Z","steps":["trace[1802089314] 'process raft request' (duration: 191.353366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:44:38.179265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.963756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-30T10:44:38.179284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.796196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bzkx7\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-03-30T10:44:38.179352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.548961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-30T10:44:38.179459Z","caller":"traceutil/trace.go:171","msg":"trace[1213335444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9499; }","duration":"301.206532ms","start":"2026-03-30T10:44:37.878228Z","end":"2026-03-30T10:44:38.179435Z","steps":["trace[1213335444] 'agreement among raft nodes before linearized reading' (duration: 300.830454ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:44:38.17948Z","caller":"traceutil/trace.go:171","msg":"trace[139178259] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bzkx7; range_end:; response_count:1; response_revision:9499; }","duration":"216.99326ms","start":"2026-03-30T10:44:37.962426Z","end":"2026-03-30T10:44:38.179419Z","steps":["trace[139178259] 'agreement among raft nodes before linearized reading' (duration: 216.661973ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:44:38.179537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:44:37.878209Z","time spent":"301.311435ms","remote":"127.0.0.1:53568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-30T10:44:38.179566Z","caller":"traceutil/trace.go:171","msg":"trace[1009918341] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9499; }","duration":"298.757455ms","start":"2026-03-30T10:44:37.880744Z","end":"2026-03-30T10:44:38.179501Z","steps":["trace[1009918341] 'agreement among raft nodes before linearized reading' (duration: 298.401898ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:44:38.180073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.292403ms","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-03-30T10:44:38.180197Z","caller":"traceutil/trace.go:171","msg":"trace[2112092475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9499; }","duration":"300.485599ms","start":"2026-03-30T10:44:37.879691Z","end":"2026-03-30T10:44:38.180176Z","steps":["trace[2112092475] 'agreement among raft nodes before linearized reading' (duration: 299.614152ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:44:38.180331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T10:44:37.87967Z","time spent":"300.621701ms","remote":"127.0.0.1:53568","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-03-30T10:44:38.358987Z","caller":"traceutil/trace.go:171","msg":"trace[5277697] linearizableReadLoop","detail":"{readStateIndex:9843; appliedIndex:9840; }","duration":"174.819713ms","start":"2026-03-30T10:44:38.184142Z","end":"2026-03-30T10:44:38.358962Z","steps":["trace[5277697] 'read index received' (duration: 26.066743ms)","trace[5277697] 'applied index is now lower than readState.Index' (duration: 148.75165ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:44:38.359327Z","caller":"traceutil/trace.go:171","msg":"trace[1077430172] transaction","detail":"{read_only:false; response_revision:9501; number_of_response:1; }","duration":"176.046686ms","start":"2026-03-30T10:44:38.18326Z","end":"2026-03-30T10:44:38.359306Z","steps":["trace[1077430172] 'process raft request' (duration: 162.776676ms)","trace[1077430172] 'compare' (duration: 12.664059ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T10:44:38.359548Z","caller":"traceutil/trace.go:171","msg":"trace[395693584] transaction","detail":"{read_only:false; response_revision:9502; number_of_response:1; }","duration":"175.658268ms","start":"2026-03-30T10:44:38.183878Z","end":"2026-03-30T10:44:38.359536Z","steps":["trace[395693584] 'process raft request' (duration: 174.986226ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:44:38.359841Z","caller":"traceutil/trace.go:171","msg":"trace[1916146007] transaction","detail":"{read_only:false; response_revision:9503; number_of_response:1; }","duration":"175.399354ms","start":"2026-03-30T10:44:38.184407Z","end":"2026-03-30T10:44:38.359806Z","steps":["trace[1916146007] 'process raft request' (duration: 174.507418ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:44:38.360016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.853082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:31 size:291896"} {"level":"info","ts":"2026-03-30T10:44:38.360066Z","caller":"traceutil/trace.go:171","msg":"trace[868522645] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:31; response_revision:9503; }","duration":"175.916904ms","start":"2026-03-30T10:44:38.184136Z","end":"2026-03-30T10:44:38.360053Z","steps":["trace[868522645] 'agreement among raft nodes before linearized reading' (duration: 175.476815ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T10:44:38.422109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.361567ms","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-03-30T10:44:38.422168Z","caller":"traceutil/trace.go:171","msg":"trace[77276448] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9503; }","duration":"118.473679ms","start":"2026-03-30T10:44:38.303682Z","end":"2026-03-30T10:44:38.422155Z","steps":["trace[77276448] 'agreement among raft nodes before linearized reading' (duration: 118.380427ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T10:44:56.449659Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6894f7e0c0041bce","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-30T10:44:56.45634Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-30T10:44:56.456434Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-30T10:47:10.131967Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8101} {"level":"info","ts":"2026-03-30T10:47:10.206782Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8101,"took":"71.122354ms","hash":833367772,"current-db-size-bytes":57335808,"current-db-size":"57 MB","current-db-size-in-use-bytes":28446720,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-30T10:47:10.206859Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":833367772,"revision":8101,"compact-revision":6151} {"level":"info","ts":"2026-03-30T10:52:10.137777Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10536} {"level":"info","ts":"2026-03-30T10:52:10.221297Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10536,"took":"81.215396ms","hash":490152290,"current-db-size-bytes":57335808,"current-db-size":"57 MB","current-db-size-in-use-bytes":21868544,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-03-30T10:52:10.221378Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":490152290,"revision":10536,"compact-revision":8101}