{"level":"warn","ts":"2026-05-14T09:29:02.180947Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T09:29:02.181061Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.15: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.15:2380","--initial-cluster=instance=https://199.19.213.15:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.15:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.15: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-05-14T09:29:02.181139Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T09:29:02.181147Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.15:2380"]} {"level":"info","ts":"2026-05-14T09:29:02.181166Z","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-05-14T09:29:02.181867Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.15:2379"]} {"level":"info","ts":"2026-05-14T09:29:02.181957Z","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":8,"max-cpu-available":8,"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.15:2380"],"listen-peer-urls":["https://199.19.213.15:2380"],"advertise-client-urls":["https://199.19.213.15:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.15:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.15: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-05-14T09:29:02.186282Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.090482ms"} {"level":"info","ts":"2026-05-14T09:29:02.193452Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11371113779d2d76","cluster-id":"1921549e667ce7d9"} {"level":"info","ts":"2026-05-14T09:29:02.193654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 switched to configuration voters=()"} {"level":"info","ts":"2026-05-14T09:29:02.193724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 became follower at term 0"} {"level":"info","ts":"2026-05-14T09:29:02.193769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11371113779d2d76 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-14T09:29:02.193821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 became follower at term 1"} {"level":"info","ts":"2026-05-14T09:29:02.193902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 switched to configuration voters=(1240478997672701302)"} {"level":"warn","ts":"2026-05-14T09:29:02.200743Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-14T09:29:02.202257Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-14T09:29:02.205254Z","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-05-14T09:29:02.211017Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11371113779d2d76","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-14T09:29:02.211148Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11371113779d2d76","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-14T09:29:02.211351Z","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-05-14T09:29:02.213558Z","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-05-14T09:29:02.213578Z","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-05-14T09:29:02.214009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 switched to configuration voters=(1240478997672701302)"} {"level":"info","ts":"2026-05-14T09:29:02.214351Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1921549e667ce7d9","local-member-id":"11371113779d2d76","added-peer-id":"11371113779d2d76","added-peer-peer-urls":["https://199.19.213.15:2380"]} {"level":"info","ts":"2026-05-14T09:29:02.21807Z","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-05-14T09:29:02.218136Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.15:2380"} {"level":"info","ts":"2026-05-14T09:29:02.218171Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.15:2380"} {"level":"info","ts":"2026-05-14T09:29:02.21831Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11371113779d2d76","initial-advertise-peer-urls":["https://199.19.213.15:2380"],"listen-peer-urls":["https://199.19.213.15:2380"],"advertise-client-urls":["https://199.19.213.15:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.15:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-14T09:29:02.218349Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-14T09:29:03.195156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 is starting a new election at term 1"} {"level":"info","ts":"2026-05-14T09:29:03.19523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-14T09:29:03.195278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 received MsgPreVoteResp from 11371113779d2d76 at term 1"} {"level":"info","ts":"2026-05-14T09:29:03.195305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 became candidate at term 2"} {"level":"info","ts":"2026-05-14T09:29:03.195322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 received MsgVoteResp from 11371113779d2d76 at term 2"} {"level":"info","ts":"2026-05-14T09:29:03.195418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11371113779d2d76 became leader at term 2"} {"level":"info","ts":"2026-05-14T09:29:03.195441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11371113779d2d76 elected leader 11371113779d2d76 at term 2"} {"level":"info","ts":"2026-05-14T09:29:03.196643Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T09:29:03.196798Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11371113779d2d76","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.15:2379]}","request-path":"/0/members/11371113779d2d76/attributes","cluster-id":"1921549e667ce7d9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-14T09:29:03.196812Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T09:29:03.197556Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-14T09:29:03.197692Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-14T09:29:03.197742Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1921549e667ce7d9","local-member-id":"11371113779d2d76","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T09:29:03.197859Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T09:29:03.197898Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T09:29:03.200059Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-14T09:29:03.200302Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T09:29:03.206503Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.15:2379"} {"level":"warn","ts":"2026-05-14T09:29:12.358288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.77728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-14T09:29:12.358389Z","caller":"traceutil/trace.go:171","msg":"trace[1513292233] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:296; }","duration":"117.924431ms","start":"2026-05-14T09:29:12.240445Z","end":"2026-05-14T09:29:12.35837Z","steps":["trace[1513292233] 'range keys from in-memory index tree' (duration: 117.705929ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:13.895236Z","caller":"traceutil/trace.go:171","msg":"trace[503429661] linearizableReadLoop","detail":"{readStateIndex:335; appliedIndex:334; }","duration":"104.288568ms","start":"2026-05-14T09:29:13.790928Z","end":"2026-05-14T09:29:13.895216Z","steps":["trace[503429661] 'read index received' (duration: 97.540927ms)","trace[503429661] 'applied index is now lower than readState.Index' (duration: 6.746481ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:29:13.895315Z","caller":"traceutil/trace.go:171","msg":"trace[1138626928] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"129.077722ms","start":"2026-05-14T09:29:13.766205Z","end":"2026-05-14T09:29:13.895282Z","steps":["trace[1138626928] 'process raft request' (duration: 122.350981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:29:13.895371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.43293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-14T09:29:13.895419Z","caller":"traceutil/trace.go:171","msg":"trace[437451668] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:326; }","duration":"104.52367ms","start":"2026-05-14T09:29:13.790885Z","end":"2026-05-14T09:29:13.895408Z","steps":["trace[437451668] 'agreement among raft nodes before linearized reading' (duration: 104.412409ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:14.031187Z","caller":"traceutil/trace.go:171","msg":"trace[834731876] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"109.531529ms","start":"2026-05-14T09:29:13.9216Z","end":"2026-05-14T09:29:14.031132Z","steps":["trace[834731876] 'process raft request' (duration: 86.163205ms)","trace[834731876] 'compare' (duration: 23.167062ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:29:14.240306Z","caller":"traceutil/trace.go:171","msg":"trace[1093300020] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:344; }","duration":"111.368267ms","start":"2026-05-14T09:29:14.128906Z","end":"2026-05-14T09:29:14.240274Z","steps":["trace[1093300020] 'read index received' (duration: 110.041213ms)","trace[1093300020] 'applied index is now lower than readState.Index' (duration: 1.324474ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:29:14.240395Z","caller":"traceutil/trace.go:171","msg":"trace[1124112708] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"111.525539ms","start":"2026-05-14T09:29:14.128837Z","end":"2026-05-14T09:29:14.240362Z","steps":["trace[1124112708] 'process raft request' (duration: 110.080893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:29:14.240533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.601899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-cs8qb\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-05-14T09:29:14.240578Z","caller":"traceutil/trace.go:171","msg":"trace[186115953] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-cs8qb; range_end:; response_count:1; response_revision:336; }","duration":"111.68982ms","start":"2026-05-14T09:29:14.128875Z","end":"2026-05-14T09:29:14.240565Z","steps":["trace[186115953] 'agreement among raft nodes before linearized reading' (duration: 111.508068ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:14.25104Z","caller":"traceutil/trace.go:171","msg":"trace[59401168] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"121.761255ms","start":"2026-05-14T09:29:14.129262Z","end":"2026-05-14T09:29:14.251023Z","steps":["trace[59401168] 'process raft request' (duration: 121.470622ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:14.251169Z","caller":"traceutil/trace.go:171","msg":"trace[780903659] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"118.128717ms","start":"2026-05-14T09:29:14.133023Z","end":"2026-05-14T09:29:14.251151Z","steps":["trace[780903659] 'process raft request' (duration: 117.966536ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:14.251173Z","caller":"traceutil/trace.go:171","msg":"trace[1895516038] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"120.065398ms","start":"2026-05-14T09:29:14.131019Z","end":"2026-05-14T09:29:14.251085Z","steps":["trace[1895516038] 'process raft request' (duration: 119.906936ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:14.2514Z","caller":"traceutil/trace.go:171","msg":"trace[1841083389] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"117.618053ms","start":"2026-05-14T09:29:14.133763Z","end":"2026-05-14T09:29:14.251381Z","steps":["trace[1841083389] 'process raft request' (duration: 117.289418ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:14.2518Z","caller":"traceutil/trace.go:171","msg":"trace[149555518] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"110.975904ms","start":"2026-05-14T09:29:14.140793Z","end":"2026-05-14T09:29:14.251769Z","steps":["trace[149555518] 'process raft request' (duration: 110.535309ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:27.211735Z","caller":"traceutil/trace.go:171","msg":"trace[2024075168] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"457.384802ms","start":"2026-05-14T09:29:26.75433Z","end":"2026-05-14T09:29:27.211715Z","steps":["trace[2024075168] 'read index received' (duration: 457.254121ms)","trace[2024075168] 'applied index is now lower than readState.Index' (duration: 127.611µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:29:27.212167Z","caller":"traceutil/trace.go:171","msg":"trace[234724643] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"521.557102ms","start":"2026-05-14T09:29:26.690597Z","end":"2026-05-14T09:29:27.212155Z","steps":["trace[234724643] 'process raft request' (duration: 520.993447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:29:27.212688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:29:26.690565Z","time spent":"521.644222ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:29:27.21286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.549713ms","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-05-14T09:29:27.212902Z","caller":"traceutil/trace.go:171","msg":"trace[550113017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:421; }","duration":"458.637623ms","start":"2026-05-14T09:29:26.754252Z","end":"2026-05-14T09:29:27.21289Z","steps":["trace[550113017] 'agreement among raft nodes before linearized reading' (duration: 458.570163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:29:27.21294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:29:26.754229Z","time spent":"458.704254ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T09:29:27.213101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.793687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-14T09:29:27.213171Z","caller":"traceutil/trace.go:171","msg":"trace[561408724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:421; }","duration":"194.903208ms","start":"2026-05-14T09:29:27.01825Z","end":"2026-05-14T09:29:27.213153Z","steps":["trace[561408724] 'agreement among raft nodes before linearized reading' (duration: 194.777647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:29:29.500832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.069027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T09:29:29.501372Z","caller":"traceutil/trace.go:171","msg":"trace[1171152158] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"266.496706ms","start":"2026-05-14T09:29:29.234856Z","end":"2026-05-14T09:29:29.501352Z","steps":["trace[1171152158] 'process raft request' (duration: 266.440186ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:29.501518Z","caller":"traceutil/trace.go:171","msg":"trace[1168801268] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"266.763948ms","start":"2026-05-14T09:29:29.234737Z","end":"2026-05-14T09:29:29.501501Z","steps":["trace[1168801268] 'process raft request' (duration: 160.786274ms)","trace[1168801268] 'compare' (duration: 104.883715ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:29:33.086823Z","caller":"traceutil/trace.go:171","msg":"trace[904754996] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"448.472101ms","start":"2026-05-14T09:29:32.638323Z","end":"2026-05-14T09:29:33.086795Z","steps":["trace[904754996] 'read index received' (duration: 448.266959ms)","trace[904754996] 'applied index is now lower than readState.Index' (duration: 203.872µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:29:33.086914Z","caller":"traceutil/trace.go:171","msg":"trace[400500424] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"468.863303ms","start":"2026-05-14T09:29:32.617998Z","end":"2026-05-14T09:29:33.086861Z","steps":["trace[400500424] 'process raft request' (duration: 468.649552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:29:33.086961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.608502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:29:33.087001Z","caller":"traceutil/trace.go:171","msg":"trace[1932429359] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:467; }","duration":"448.680792ms","start":"2026-05-14T09:29:32.638307Z","end":"2026-05-14T09:29:33.086988Z","steps":["trace[1932429359] 'agreement among raft nodes before linearized reading' (duration: 448.599012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:29:33.08705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:29:32.638301Z","time spent":"448.739193ms","remote":"127.0.0.1:33472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-14T09:29:33.087096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:29:32.617988Z","time spent":"469.006904ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:29:33.833756Z","caller":"traceutil/trace.go:171","msg":"trace[1450703345] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"259.877339ms","start":"2026-05-14T09:29:33.573834Z","end":"2026-05-14T09:29:33.833712Z","steps":["trace[1450703345] 'process raft request' (duration: 252.780739ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:33.834475Z","caller":"traceutil/trace.go:171","msg":"trace[1135757926] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"237.130248ms","start":"2026-05-14T09:29:33.597314Z","end":"2026-05-14T09:29:33.834444Z","steps":["trace[1135757926] 'process raft request' (duration: 236.896286ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:33.834672Z","caller":"traceutil/trace.go:171","msg":"trace[2086520749] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"210.728437ms","start":"2026-05-14T09:29:33.62393Z","end":"2026-05-14T09:29:33.834658Z","steps":["trace[2086520749] 'process raft request' (duration: 210.411414ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:33.834691Z","caller":"traceutil/trace.go:171","msg":"trace[1787796300] transaction","detail":"{read_only:false; number_of_response:1; response_revision:518; }","duration":"206.507701ms","start":"2026-05-14T09:29:33.628156Z","end":"2026-05-14T09:29:33.834664Z","steps":["trace[1787796300] 'process raft request' (duration: 206.32567ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:35.178336Z","caller":"traceutil/trace.go:171","msg":"trace[1449353795] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"557.66857ms","start":"2026-05-14T09:29:34.620644Z","end":"2026-05-14T09:29:35.178313Z","steps":["trace[1449353795] 'process raft request' (duration: 557.518849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:29:35.178501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:29:34.620624Z","time spent":"557.784021ms","remote":"127.0.0.1:33428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:29:35.22342Z","caller":"traceutil/trace.go:171","msg":"trace[1361507909] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"597.8162ms","start":"2026-05-14T09:29:34.625538Z","end":"2026-05-14T09:29:35.223354Z","steps":["trace[1361507909] 'process raft request' (duration: 595.833433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:29:35.225301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:29:34.625514Z","time spent":"598.025531ms","remote":"127.0.0.1:33428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T09:29:35.226847Z","caller":"traceutil/trace.go:171","msg":"trace[1020748249] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"459.482721ms","start":"2026-05-14T09:29:34.767341Z","end":"2026-05-14T09:29:35.226823Z","steps":["trace[1020748249] 'process raft request' (duration: 458.663145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:29:35.226968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:29:34.767314Z","time spent":"459.592542ms","remote":"127.0.0.1:33428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:29:35.227103Z","caller":"traceutil/trace.go:171","msg":"trace[1904677502] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"172.690371ms","start":"2026-05-14T09:29:35.054385Z","end":"2026-05-14T09:29:35.227075Z","steps":["trace[1904677502] 'process raft request' (duration: 172.398059ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:35.227111Z","caller":"traceutil/trace.go:171","msg":"trace[1592742213] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:558; }","duration":"124.078051ms","start":"2026-05-14T09:29:35.102944Z","end":"2026-05-14T09:29:35.227022Z","steps":["trace[1592742213] 'read index received' (duration: 76.053246ms)","trace[1592742213] 'applied index is now lower than readState.Index' (duration: 48.021985ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:29:35.22726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.300333ms","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-05-14T09:29:35.227313Z","caller":"traceutil/trace.go:171","msg":"trace[706608782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:543; }","duration":"124.386124ms","start":"2026-05-14T09:29:35.102911Z","end":"2026-05-14T09:29:35.227297Z","steps":["trace[706608782] 'agreement among raft nodes before linearized reading' (duration: 124.245333ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:39.491025Z","caller":"traceutil/trace.go:171","msg":"trace[1249396896] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"230.827494ms","start":"2026-05-14T09:29:39.260169Z","end":"2026-05-14T09:29:39.490996Z","steps":["trace[1249396896] 'read index received' (duration: 230.732473ms)","trace[1249396896] 'applied index is now lower than readState.Index' (duration: 93.971µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:29:39.491092Z","caller":"traceutil/trace.go:171","msg":"trace[325310866] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"239.463612ms","start":"2026-05-14T09:29:39.251592Z","end":"2026-05-14T09:29:39.491056Z","steps":["trace[325310866] 'process raft request' (duration: 239.19869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:29:39.491271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.087057ms","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-05-14T09:29:39.491342Z","caller":"traceutil/trace.go:171","msg":"trace[483575626] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:572; }","duration":"231.188588ms","start":"2026-05-14T09:29:39.260114Z","end":"2026-05-14T09:29:39.491302Z","steps":["trace[483575626] 'agreement among raft nodes before linearized reading' (duration: 230.980705ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:39.684609Z","caller":"traceutil/trace.go:171","msg":"trace[1019223882] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"228.294225ms","start":"2026-05-14T09:29:39.45629Z","end":"2026-05-14T09:29:39.684585Z","steps":["trace[1019223882] 'process raft request' (duration: 227.73121ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:39.700671Z","caller":"traceutil/trace.go:171","msg":"trace[997480222] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:591; }","duration":"153.893443ms","start":"2026-05-14T09:29:39.546761Z","end":"2026-05-14T09:29:39.700654Z","steps":["trace[997480222] 'read index received' (duration: 137.15197ms)","trace[997480222] 'applied index is now lower than readState.Index' (duration: 16.740713ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:29:39.700821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.047275ms","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-05-14T09:29:39.700848Z","caller":"traceutil/trace.go:171","msg":"trace[483608113] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"154.110665ms","start":"2026-05-14T09:29:39.54673Z","end":"2026-05-14T09:29:39.700841Z","steps":["trace[483608113] 'agreement among raft nodes before linearized reading' (duration: 153.996294ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:39.700952Z","caller":"traceutil/trace.go:171","msg":"trace[1822362766] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"205.874576ms","start":"2026-05-14T09:29:39.495043Z","end":"2026-05-14T09:29:39.700918Z","steps":["trace[1822362766] 'process raft request' (duration: 205.536174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:29:39.702367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.128595ms","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-05-14T09:29:39.702436Z","caller":"traceutil/trace.go:171","msg":"trace[1226511048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:574; }","duration":"154.239026ms","start":"2026-05-14T09:29:39.54818Z","end":"2026-05-14T09:29:39.702419Z","steps":["trace[1226511048] 'agreement among raft nodes before linearized reading' (duration: 153.077236ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:40.008094Z","caller":"traceutil/trace.go:171","msg":"trace[2074775963] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"302.292493ms","start":"2026-05-14T09:29:39.705765Z","end":"2026-05-14T09:29:40.008058Z","steps":["trace[2074775963] 'process raft request' (duration: 254.338702ms)","trace[2074775963] 'compare' (duration: 47.521388ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:29:40.008372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:29:39.705746Z","time spent":"302.416834ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:29:40.008415Z","caller":"traceutil/trace.go:171","msg":"trace[472178662] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"302.323792ms","start":"2026-05-14T09:29:39.70606Z","end":"2026-05-14T09:29:40.008384Z","steps":["trace[472178662] 'process raft request' (duration: 301.870939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:29:40.008676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:29:39.706019Z","time spent":"302.466354ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:29:40.008174Z","caller":"traceutil/trace.go:171","msg":"trace[442225990] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"271.959391ms","start":"2026-05-14T09:29:39.736162Z","end":"2026-05-14T09:29:40.008122Z","steps":["trace[442225990] 'process raft request' (duration: 271.879851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:29:40.812904Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3275979664223106775,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T09:29:41.121782Z","caller":"traceutil/trace.go:171","msg":"trace[704006216] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"1.060296191s","start":"2026-05-14T09:29:40.061458Z","end":"2026-05-14T09:29:41.121754Z","steps":["trace[704006216] 'process raft request' (duration: 999.893788ms)","trace[704006216] 'compare' (duration: 59.982129ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:29:41.121931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:29:40.061431Z","time spent":"1.060417502s","remote":"127.0.0.1:33554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:29:41.12364Z","caller":"traceutil/trace.go:171","msg":"trace[207215863] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:596; }","duration":"811.667981ms","start":"2026-05-14T09:29:40.311948Z","end":"2026-05-14T09:29:41.123616Z","steps":["trace[207215863] 'read index received' (duration: 749.457275ms)","trace[207215863] 'applied index is now lower than readState.Index' (duration: 62.209636ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:29:41.123688Z","caller":"traceutil/trace.go:171","msg":"trace[320530829] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"717.532318ms","start":"2026-05-14T09:29:40.406135Z","end":"2026-05-14T09:29:41.123667Z","steps":["trace[320530829] 'process raft request' (duration: 715.825025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:29:41.123746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.780072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T09:29:41.124065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:29:40.406117Z","time spent":"717.610109ms","remote":"127.0.0.1:33472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:29:41.124615Z","caller":"traceutil/trace.go:171","msg":"trace[724983151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"811.866222ms","start":"2026-05-14T09:29:40.311909Z","end":"2026-05-14T09:29:41.123775Z","steps":["trace[724983151] 'agreement among raft nodes before linearized reading' (duration: 811.793251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:29:41.124749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:29:40.311889Z","time spent":"812.832251ms","remote":"127.0.0.1:33378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T09:29:42.451339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.587664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:29:42.451403Z","caller":"traceutil/trace.go:171","msg":"trace[462259614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"138.712496ms","start":"2026-05-14T09:29:42.312673Z","end":"2026-05-14T09:29:42.451386Z","steps":["trace[462259614] 'range keys from in-memory index tree' (duration: 138.507204ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:29:57.254221Z","caller":"traceutil/trace.go:171","msg":"trace[534727275] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"436.57708ms","start":"2026-05-14T09:29:56.817613Z","end":"2026-05-14T09:29:57.25419Z","steps":["trace[534727275] 'process raft request' (duration: 436.414939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:29:57.254402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:29:56.817587Z","time spent":"436.719681ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:30:11.535491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.909017ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-14T09:30:11.535739Z","caller":"traceutil/trace.go:171","msg":"trace[1079431766] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"200.581291ms","start":"2026-05-14T09:30:11.335069Z","end":"2026-05-14T09:30:11.53565Z","steps":["trace[1079431766] 'read index received' (duration: 42.132µs)","trace[1079431766] 'applied index is now lower than readState.Index' (duration: 200.537559ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:30:11.535937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.846924ms","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-05-14T09:30:11.536075Z","caller":"traceutil/trace.go:171","msg":"trace[673147221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:913; }","duration":"201.003801ms","start":"2026-05-14T09:30:11.334998Z","end":"2026-05-14T09:30:11.536002Z","steps":["trace[673147221] 'agreement among raft nodes before linearized reading' (duration: 200.76218ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:30:26.795029Z","caller":"traceutil/trace.go:171","msg":"trace[763876247] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"568.168924ms","start":"2026-05-14T09:30:26.226832Z","end":"2026-05-14T09:30:26.795001Z","steps":["trace[763876247] 'process raft request' (duration: 568.013018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:30:26.79524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:30:26.226821Z","time spent":"568.335221ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:30:26.797114Z","caller":"traceutil/trace.go:171","msg":"trace[903705925] linearizableReadLoop","detail":"{readStateIndex:1183; appliedIndex:1182; }","duration":"280.967542ms","start":"2026-05-14T09:30:26.516121Z","end":"2026-05-14T09:30:26.797089Z","steps":["trace[903705925] 'read index received' (duration: 278.897382ms)","trace[903705925] 'applied index is now lower than readState.Index' (duration: 2.06893ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:30:26.797431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.292605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T09:30:26.797542Z","caller":"traceutil/trace.go:171","msg":"trace[2138871771] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1151; }","duration":"281.472281ms","start":"2026-05-14T09:30:26.516051Z","end":"2026-05-14T09:30:26.797523Z","steps":["trace[2138871771] 'agreement among raft nodes before linearized reading' (duration: 281.126268ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:30:26.797973Z","caller":"traceutil/trace.go:171","msg":"trace[1965117621] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"454.092098ms","start":"2026-05-14T09:30:26.343858Z","end":"2026-05-14T09:30:26.79795Z","steps":["trace[1965117621] 'process raft request' (duration: 453.090669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:30:26.798257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:30:26.34383Z","time spent":"454.350698ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:30:49.075717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.83987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T09:30:49.075794Z","caller":"traceutil/trace.go:171","msg":"trace[682187514] linearizableReadLoop","detail":"{readStateIndex:1443; appliedIndex:1442; }","duration":"178.279666ms","start":"2026-05-14T09:30:48.897502Z","end":"2026-05-14T09:30:49.075781Z","steps":["trace[682187514] 'read index received' (duration: 11.25116ms)","trace[682187514] 'applied index is now lower than readState.Index' (duration: 167.027926ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:30:49.075821Z","caller":"traceutil/trace.go:171","msg":"trace[2040278202] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"184.9967ms","start":"2026-05-14T09:30:48.890798Z","end":"2026-05-14T09:30:49.075795Z","steps":["trace[2040278202] 'process raft request' (duration: 18.010085ms)","trace[2040278202] 'compare' (duration: 166.766248ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:30:49.075884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.38524ms","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-05-14T09:30:49.075901Z","caller":"traceutil/trace.go:171","msg":"trace[1961844853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1403; }","duration":"178.430091ms","start":"2026-05-14T09:30:48.897465Z","end":"2026-05-14T09:30:49.075895Z","steps":["trace[1961844853] 'agreement among raft nodes before linearized reading' (duration: 178.348808ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:30:52.079511Z","caller":"traceutil/trace.go:171","msg":"trace[1594169524] linearizableReadLoop","detail":"{readStateIndex:1459; appliedIndex:1458; }","duration":"210.472698ms","start":"2026-05-14T09:30:51.869009Z","end":"2026-05-14T09:30:52.079481Z","steps":["trace[1594169524] 'read index received' (duration: 210.228471ms)","trace[1594169524] 'applied index is now lower than readState.Index' (duration: 243.027µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:30:52.079598Z","caller":"traceutil/trace.go:171","msg":"trace[236685060] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"244.28627ms","start":"2026-05-14T09:30:51.835262Z","end":"2026-05-14T09:30:52.079548Z","steps":["trace[236685060] 'process raft request' (duration: 243.987312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:30:52.079775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.744955ms","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-05-14T09:30:52.079823Z","caller":"traceutil/trace.go:171","msg":"trace[1781161588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1418; }","duration":"210.808677ms","start":"2026-05-14T09:30:51.869001Z","end":"2026-05-14T09:30:52.07981Z","steps":["trace[1781161588] 'agreement among raft nodes before linearized reading' (duration: 210.633522ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:31:10.076412Z","caller":"traceutil/trace.go:171","msg":"trace[76283773] transaction","detail":"{read_only:false; response_revision:1728; number_of_response:1; }","duration":"117.517172ms","start":"2026-05-14T09:31:09.958862Z","end":"2026-05-14T09:31:10.076379Z","steps":["trace[76283773] 'process raft request' (duration: 117.313677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:17.912199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.860416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-99819454-1789-45d0-a5f6-b35c884e3b76\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-14T09:31:17.91229Z","caller":"traceutil/trace.go:171","msg":"trace[1145365677] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-99819454-1789-45d0-a5f6-b35c884e3b76; range_end:; response_count:1; response_revision:1836; }","duration":"498.01588ms","start":"2026-05-14T09:31:17.414255Z","end":"2026-05-14T09:31:17.912271Z","steps":["trace[1145365677] 'range keys from in-memory index tree' (duration: 497.66104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:17.912322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:17.414234Z","time spent":"498.080951ms","remote":"127.0.0.1:33554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-99819454-1789-45d0-a5f6-b35c884e3b76\" "} {"level":"info","ts":"2026-05-14T09:31:18.026894Z","caller":"traceutil/trace.go:171","msg":"trace[37552545] linearizableReadLoop","detail":"{readStateIndex:1907; appliedIndex:1906; }","duration":"492.339213ms","start":"2026-05-14T09:31:17.534524Z","end":"2026-05-14T09:31:18.026863Z","steps":["trace[37552545] 'read index received' (duration: 492.223861ms)","trace[37552545] 'applied index is now lower than readState.Index' (duration: 114.532µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:31:18.026964Z","caller":"traceutil/trace.go:171","msg":"trace[432545436] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"568.556204ms","start":"2026-05-14T09:31:17.458359Z","end":"2026-05-14T09:31:18.026915Z","steps":["trace[432545436] 'process raft request' (duration: 568.345529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:18.027156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.61066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-99819454-1789-45d0-a5f6-b35c884e3b76\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-14T09:31:18.02725Z","caller":"traceutil/trace.go:171","msg":"trace[495203875] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-99819454-1789-45d0-a5f6-b35c884e3b76; range_end:; response_count:1; response_revision:1837; }","duration":"492.764673ms","start":"2026-05-14T09:31:17.534466Z","end":"2026-05-14T09:31:18.02723Z","steps":["trace[495203875] 'agreement among raft nodes before linearized reading' (duration: 492.529378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:18.027303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:17.534444Z","time spent":"492.843716ms","remote":"127.0.0.1:33554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-99819454-1789-45d0-a5f6-b35c884e3b76\" "} {"level":"warn","ts":"2026-05-14T09:31:18.027467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:17.45834Z","time spent":"568.709618ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:31:18.072042Z","caller":"traceutil/trace.go:171","msg":"trace[1314818329] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"537.097534ms","start":"2026-05-14T09:31:17.534918Z","end":"2026-05-14T09:31:18.072016Z","steps":["trace[1314818329] 'process raft request' (duration: 536.901289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:18.072369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:17.534876Z","time spent":"537.245357ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:31:18.072461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.904277ms","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-05-14T09:31:18.072527Z","caller":"traceutil/trace.go:171","msg":"trace[857865028] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"490.477542ms","start":"2026-05-14T09:31:17.582014Z","end":"2026-05-14T09:31:18.072491Z","steps":["trace[857865028] 'process raft request' (duration: 489.94751ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:31:18.07258Z","caller":"traceutil/trace.go:171","msg":"trace[70923226] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1839; }","duration":"169.080601ms","start":"2026-05-14T09:31:17.903483Z","end":"2026-05-14T09:31:18.072564Z","steps":["trace[70923226] 'agreement among raft nodes before linearized reading' (duration: 168.700252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:18.072712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:17.581981Z","time spent":"490.629266ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:31:18.072966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.79829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-14T09:31:18.072998Z","caller":"traceutil/trace.go:171","msg":"trace[2043544994] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1839; }","duration":"133.888212ms","start":"2026-05-14T09:31:17.939102Z","end":"2026-05-14T09:31:18.07299Z","steps":["trace[2043544994] 'agreement among raft nodes before linearized reading' (duration: 133.720099ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:31:18.137815Z","caller":"traceutil/trace.go:171","msg":"trace[1212050762] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"102.267059ms","start":"2026-05-14T09:31:18.035497Z","end":"2026-05-14T09:31:18.137764Z","steps":["trace[1212050762] 'process raft request' (duration: 84.275649ms)","trace[1212050762] 'compare' (duration: 17.826816ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:31:18.317808Z","caller":"traceutil/trace.go:171","msg":"trace[1797782534] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"154.916857ms","start":"2026-05-14T09:31:18.16286Z","end":"2026-05-14T09:31:18.317777Z","steps":["trace[1797782534] 'process raft request' (duration: 126.591302ms)","trace[1797782534] 'compare' (duration: 28.07863ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:31:18.719119Z","caller":"traceutil/trace.go:171","msg":"trace[580311212] linearizableReadLoop","detail":"{readStateIndex:1919; appliedIndex:1913; }","duration":"192.495994ms","start":"2026-05-14T09:31:18.526598Z","end":"2026-05-14T09:31:18.719094Z","steps":["trace[580311212] 'read index received' (duration: 174.494324ms)","trace[580311212] 'applied index is now lower than readState.Index' (duration: 18.00039ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:31:18.719364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.739229ms","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-05-14T09:31:18.719399Z","caller":"traceutil/trace.go:171","msg":"trace[1770503892] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1849; }","duration":"192.842572ms","start":"2026-05-14T09:31:18.526546Z","end":"2026-05-14T09:31:18.719389Z","steps":["trace[1770503892] 'agreement among raft nodes before linearized reading' (duration: 192.654397ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:31:18.719338Z","caller":"traceutil/trace.go:171","msg":"trace[148448287] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"394.300419ms","start":"2026-05-14T09:31:18.32498Z","end":"2026-05-14T09:31:18.71928Z","steps":["trace[148448287] 'process raft request' (duration: 393.937891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:18.719793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:18.324973Z","time spent":"394.604267ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:31:18.719896Z","caller":"traceutil/trace.go:171","msg":"trace[11109109] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"394.77283ms","start":"2026-05-14T09:31:18.325111Z","end":"2026-05-14T09:31:18.719884Z","steps":["trace[11109109] 'process raft request' (duration: 393.850439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:18.720003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:18.325104Z","time spent":"394.829912ms","remote":"127.0.0.1:33626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:31:18.720168Z","caller":"traceutil/trace.go:171","msg":"trace[1625108074] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"395.280192ms","start":"2026-05-14T09:31:18.324866Z","end":"2026-05-14T09:31:18.720146Z","steps":["trace[1625108074] 'process raft request' (duration: 375.488471ms)","trace[1625108074] 'compare' (duration: 16.538927ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:31:18.720154Z","caller":"traceutil/trace.go:171","msg":"trace[531619988] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"394.370021ms","start":"2026-05-14T09:31:18.32576Z","end":"2026-05-14T09:31:18.72013Z","steps":["trace[531619988] 'process raft request' (duration: 393.251106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:18.720265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:18.324843Z","time spent":"395.367903ms","remote":"127.0.0.1:33536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:31:18.720304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:18.325749Z","time spent":"394.475703ms","remote":"127.0.0.1:33536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:31:18.720409Z","caller":"traceutil/trace.go:171","msg":"trace[1639276130] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"395.456906ms","start":"2026-05-14T09:31:18.324928Z","end":"2026-05-14T09:31:18.720385Z","steps":["trace[1639276130] 'process raft request' (duration: 393.925232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:18.720507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:18.324916Z","time spent":"395.522397ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:31:18.720666Z","caller":"traceutil/trace.go:171","msg":"trace[1098833526] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"392.948298ms","start":"2026-05-14T09:31:18.327142Z","end":"2026-05-14T09:31:18.72009Z","steps":["trace[1098833526] 'process raft request' (duration: 391.910255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:18.723238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:18.327127Z","time spent":"393.617423ms","remote":"127.0.0.1:33626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:31:25.73668Z","caller":"traceutil/trace.go:171","msg":"trace[865110897] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"138.292611ms","start":"2026-05-14T09:31:25.59836Z","end":"2026-05-14T09:31:25.736653Z","steps":["trace[865110897] 'process raft request' (duration: 138.108957ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:31:26.941239Z","caller":"traceutil/trace.go:171","msg":"trace[1825922173] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"173.564927ms","start":"2026-05-14T09:31:26.767648Z","end":"2026-05-14T09:31:26.941213Z","steps":["trace[1825922173] 'process raft request' (duration: 173.281761ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:31:27.111055Z","caller":"traceutil/trace.go:171","msg":"trace[156553725] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"341.907046ms","start":"2026-05-14T09:31:26.769116Z","end":"2026-05-14T09:31:27.111023Z","steps":["trace[156553725] 'process raft request' (duration: 341.694081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:27.111238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:26.769088Z","time spent":"342.053798ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:31:27.111299Z","caller":"traceutil/trace.go:171","msg":"trace[227330903] linearizableReadLoop","detail":"{readStateIndex:1994; appliedIndex:1991; }","duration":"289.044768ms","start":"2026-05-14T09:31:26.822212Z","end":"2026-05-14T09:31:27.111256Z","steps":["trace[227330903] 'read index received' (duration: 118.607966ms)","trace[227330903] 'applied index is now lower than readState.Index' (duration: 170.434162ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:31:27.111412Z","caller":"traceutil/trace.go:171","msg":"trace[849515790] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"306.46084ms","start":"2026-05-14T09:31:26.804917Z","end":"2026-05-14T09:31:27.111378Z","steps":["trace[849515790] 'process raft request' (duration: 306.055391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:27.111556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.318795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-14T09:31:27.111596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:26.804886Z","time spent":"306.615903ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:31:27.111646Z","caller":"traceutil/trace.go:171","msg":"trace[429103489] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1922; }","duration":"289.466818ms","start":"2026-05-14T09:31:26.82216Z","end":"2026-05-14T09:31:27.111627Z","steps":["trace[429103489] 'agreement among raft nodes before linearized reading' (duration: 289.219623ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:31:31.661538Z","caller":"traceutil/trace.go:171","msg":"trace[78381006] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"384.273016ms","start":"2026-05-14T09:31:31.277226Z","end":"2026-05-14T09:31:31.661499Z","steps":["trace[78381006] 'process raft request' (duration: 383.869497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:31.66175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:31.277164Z","time spent":"384.47068ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:31:48.265525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.52965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T09:31:48.265633Z","caller":"traceutil/trace.go:171","msg":"trace[2024421625] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2057; }","duration":"167.729424ms","start":"2026-05-14T09:31:48.097881Z","end":"2026-05-14T09:31:48.265611Z","steps":["trace[2024421625] 'count revisions from in-memory index tree' (duration: 167.332247ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:31:53.997506Z","caller":"traceutil/trace.go:171","msg":"trace[1087596259] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"170.24526ms","start":"2026-05-14T09:31:53.82723Z","end":"2026-05-14T09:31:53.997475Z","steps":["trace[1087596259] 'process raft request' (duration: 169.989305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:54.536406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.448943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:31:54.536503Z","caller":"traceutil/trace.go:171","msg":"trace[2131273262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2090; }","duration":"224.610086ms","start":"2026-05-14T09:31:54.311867Z","end":"2026-05-14T09:31:54.536477Z","steps":["trace[2131273262] 'range keys from in-memory index tree' (duration: 224.29559ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:31:54.659179Z","caller":"traceutil/trace.go:171","msg":"trace[1868762640] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"346.835452ms","start":"2026-05-14T09:31:54.312281Z","end":"2026-05-14T09:31:54.659117Z","steps":["trace[1868762640] 'process raft request' (duration: 343.058277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:54.691424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:54.312264Z","time spent":"378.824407ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:31:54.659307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.194045ms","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-05-14T09:31:54.691523Z","caller":"traceutil/trace.go:171","msg":"trace[2097526033] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2091; }","duration":"322.418366ms","start":"2026-05-14T09:31:54.369076Z","end":"2026-05-14T09:31:54.691494Z","steps":["trace[2097526033] 'agreement among raft nodes before linearized reading' (duration: 290.038783ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:31:54.658982Z","caller":"traceutil/trace.go:171","msg":"trace[1208111812] linearizableReadLoop","detail":"{readStateIndex:2168; appliedIndex:2167; }","duration":"289.86598ms","start":"2026-05-14T09:31:54.36909Z","end":"2026-05-14T09:31:54.658956Z","steps":["trace[1208111812] 'read index received' (duration: 286.247288ms)","trace[1208111812] 'applied index is now lower than readState.Index' (duration: 3.613932ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:31:54.691573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:54.369038Z","time spent":"322.517158ms","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-14T09:31:54.691709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.473009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:31:54.691737Z","caller":"traceutil/trace.go:171","msg":"trace[1941594773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2091; }","duration":"153.576861ms","start":"2026-05-14T09:31:54.538152Z","end":"2026-05-14T09:31:54.691729Z","steps":["trace[1941594773] 'agreement among raft nodes before linearized reading' (duration: 153.50767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:55.251622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.4722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T09:31:55.251724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.92991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-14T09:31:55.251793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.611346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T09:31:55.25182Z","caller":"traceutil/trace.go:171","msg":"trace[1636922555] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2092; }","duration":"132.103993ms","start":"2026-05-14T09:31:55.119693Z","end":"2026-05-14T09:31:55.251797Z","steps":["trace[1636922555] 'range keys from in-memory index tree' (duration: 131.700286ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:31:55.251785Z","caller":"traceutil/trace.go:171","msg":"trace[583375023] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2092; }","duration":"326.742514ms","start":"2026-05-14T09:31:54.924994Z","end":"2026-05-14T09:31:55.251737Z","steps":["trace[583375023] 'count revisions from in-memory index tree' (duration: 326.297267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:55.251829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.593099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-05-14T09:31:55.251886Z","caller":"traceutil/trace.go:171","msg":"trace[1323564904] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2092; }","duration":"182.793189ms","start":"2026-05-14T09:31:55.06906Z","end":"2026-05-14T09:31:55.251853Z","steps":["trace[1323564904] 'count revisions from in-memory index tree' (duration: 182.461974ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:31:55.251926Z","caller":"traceutil/trace.go:171","msg":"trace[953677767] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2092; }","duration":"308.745802ms","start":"2026-05-14T09:31:54.943157Z","end":"2026-05-14T09:31:55.251903Z","steps":["trace[953677767] 'range keys from in-memory index tree' (duration: 308.419236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:55.251984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:54.943129Z","time spent":"308.844583ms","remote":"127.0.0.1:33558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2010,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-05-14T09:31:55.251922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:54.92497Z","time spent":"326.931398ms","remote":"127.0.0.1:44052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-05-14T09:31:56.023444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.19617ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:31:56.023599Z","caller":"traceutil/trace.go:171","msg":"trace[1949795760] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2095; }","duration":"412.372533ms","start":"2026-05-14T09:31:55.611203Z","end":"2026-05-14T09:31:56.023576Z","steps":["trace[1949795760] 'range keys from in-memory index tree' (duration: 412.17375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:56.024133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.705934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T09:31:56.024875Z","caller":"traceutil/trace.go:171","msg":"trace[781246194] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"683.409106ms","start":"2026-05-14T09:31:55.341417Z","end":"2026-05-14T09:31:56.024826Z","steps":["trace[781246194] 'process raft request' (duration: 429.890757ms)","trace[781246194] 'compare' (duration: 252.203686ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:31:56.02501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:55.341326Z","time spent":"683.640119ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:31:56.11344Z","caller":"traceutil/trace.go:171","msg":"trace[2016817149] linearizableReadLoop","detail":"{readStateIndex:2175; appliedIndex:2172; }","duration":"481.539098ms","start":"2026-05-14T09:31:55.631869Z","end":"2026-05-14T09:31:56.113408Z","steps":["trace[2016817149] 'read index received' (duration: 139.449019ms)","trace[2016817149] 'applied index is now lower than readState.Index' (duration: 342.089009ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:31:56.113572Z","caller":"traceutil/trace.go:171","msg":"trace[670276693] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"763.112322ms","start":"2026-05-14T09:31:55.350387Z","end":"2026-05-14T09:31:56.1135Z","steps":["trace[670276693] 'process raft request' (duration: 762.97281ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:31:56.113511Z","caller":"traceutil/trace.go:171","msg":"trace[660131341] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"763.209804ms","start":"2026-05-14T09:31:55.350256Z","end":"2026-05-14T09:31:56.113466Z","steps":["trace[660131341] 'process raft request' (duration: 762.869938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:56.113858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.975295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18af64b4d14026db\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-05-14T09:31:56.11386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:55.350359Z","time spent":"763.330446ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:31:56.113978Z","caller":"traceutil/trace.go:171","msg":"trace[498616686] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18af64b4d14026db; range_end:; response_count:1; response_revision:2098; }","duration":"482.122868ms","start":"2026-05-14T09:31:55.631808Z","end":"2026-05-14T09:31:56.113931Z","steps":["trace[498616686] 'agreement among raft nodes before linearized reading' (duration: 481.825003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:56.113961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:55.350245Z","time spent":"763.523029ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:31:56.114096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:55.631782Z","time spent":"482.262821ms","remote":"127.0.0.1:33466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":868,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18af64b4d14026db\" "} {"level":"warn","ts":"2026-05-14T09:31:56.267164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.277126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T09:31:56.267429Z","caller":"traceutil/trace.go:171","msg":"trace[85644030] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"150.744468ms","start":"2026-05-14T09:31:56.116662Z","end":"2026-05-14T09:31:56.267407Z","steps":["trace[85644030] 'process raft request' (duration: 150.622406ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:31:56.267349Z","caller":"traceutil/trace.go:171","msg":"trace[1235890081] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"488.047914ms","start":"2026-05-14T09:31:55.779242Z","end":"2026-05-14T09:31:56.26729Z","steps":["trace[1235890081] 'process raft request' (duration: 353.529014ms)","trace[1235890081] 'compare' (duration: 134.049552ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:31:56.267546Z","caller":"traceutil/trace.go:171","msg":"trace[63316627] linearizableReadLoop","detail":"{readStateIndex:2176; appliedIndex:2175; }","duration":"153.935253ms","start":"2026-05-14T09:31:56.113595Z","end":"2026-05-14T09:31:56.26753Z","steps":["trace[63316627] 'read index received' (duration: 19.162519ms)","trace[63316627] 'applied index is now lower than readState.Index' (duration: 134.771034ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:31:56.267781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.726763ms","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-05-14T09:31:56.267855Z","caller":"traceutil/trace.go:171","msg":"trace[400376991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2100; }","duration":"265.867485ms","start":"2026-05-14T09:31:56.001976Z","end":"2026-05-14T09:31:56.267844Z","steps":["trace[400376991] 'agreement among raft nodes before linearized reading' (duration: 265.718343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:56.267923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.361319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T09:31:56.267957Z","caller":"traceutil/trace.go:171","msg":"trace[1730650996] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2100; }","duration":"367.45873ms","start":"2026-05-14T09:31:55.90049Z","end":"2026-05-14T09:31:56.267949Z","steps":["trace[1730650996] 'agreement among raft nodes before linearized reading' (duration: 367.399279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:56.267982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:55.900459Z","time spent":"367.516182ms","remote":"127.0.0.1:33726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":29,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-14T09:31:56.268055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.672719ms","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-05-14T09:31:56.268115Z","caller":"traceutil/trace.go:171","msg":"trace[1816104852] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2100; }","duration":"567.761031ms","start":"2026-05-14T09:31:55.700338Z","end":"2026-05-14T09:31:56.268099Z","steps":["trace[1816104852] 'agreement among raft nodes before linearized reading' (duration: 567.522917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:56.268157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:55.700216Z","time spent":"567.934654ms","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-14T09:31:56.267802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:55.77922Z","time spent":"488.38943ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:31:57.287994Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3275979664223109887,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T09:31:57.389049Z","caller":"traceutil/trace.go:171","msg":"trace[1901455781] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"689.945334ms","start":"2026-05-14T09:31:56.699079Z","end":"2026-05-14T09:31:57.389024Z","steps":["trace[1901455781] 'process raft request' (duration: 689.776451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:57.389379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:56.699033Z","time spent":"690.24269ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:31:57.821618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.033654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T09:31:57.821805Z","caller":"traceutil/trace.go:171","msg":"trace[944410737] linearizableReadLoop","detail":"{readStateIndex:2182; appliedIndex:2180; }","duration":"1.034466432s","start":"2026-05-14T09:31:56.787254Z","end":"2026-05-14T09:31:57.821721Z","steps":["trace[944410737] 'read index received' (duration: 601.591786ms)","trace[944410737] 'applied index is now lower than readState.Index' (duration: 432.873326ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:31:57.821897Z","caller":"traceutil/trace.go:171","msg":"trace[402554357] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"562.541548ms","start":"2026-05-14T09:31:57.259274Z","end":"2026-05-14T09:31:57.821816Z","steps":["trace[402554357] 'process raft request' (duration: 376.206829ms)","trace[402554357] 'compare' (duration: 185.835861ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:31:57.822111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:57.25924Z","time spent":"562.768441ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:31:57.82223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034970571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-14T09:31:57.822314Z","caller":"traceutil/trace.go:171","msg":"trace[315051926] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2104; }","duration":"1.035105473s","start":"2026-05-14T09:31:56.787195Z","end":"2026-05-14T09:31:57.8223Z","steps":["trace[315051926] 'agreement among raft nodes before linearized reading' (duration: 1.034742957s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:57.822845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:56.787169Z","time spent":"1.035607411s","remote":"127.0.0.1:33622","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-05-14T09:31:57.822892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.870032ms","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":"warn","ts":"2026-05-14T09:31:57.822567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.094594ms","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-05-14T09:31:57.823028Z","caller":"traceutil/trace.go:171","msg":"trace[1418290572] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2104; }","duration":"546.066886ms","start":"2026-05-14T09:31:57.276937Z","end":"2026-05-14T09:31:57.823004Z","steps":["trace[1418290572] 'agreement among raft nodes before linearized reading' (duration: 545.649339ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:31:57.823085Z","caller":"traceutil/trace.go:171","msg":"trace[378061169] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2104; }","duration":"734.675614ms","start":"2026-05-14T09:31:57.088387Z","end":"2026-05-14T09:31:57.823062Z","steps":["trace[378061169] 'agreement among raft nodes before linearized reading' (duration: 734.085524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:57.82273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.266141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-14T09:31:57.823138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:57.088367Z","time spent":"734.758517ms","remote":"127.0.0.1:54790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-05-14T09:31:57.823119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:57.276914Z","time spent":"546.192439ms","remote":"127.0.0.1:49354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-14T09:31:57.822883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.110817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-14T09:31:57.823174Z","caller":"traceutil/trace.go:171","msg":"trace[726043447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2104; }","duration":"549.740779ms","start":"2026-05-14T09:31:57.273417Z","end":"2026-05-14T09:31:57.823158Z","steps":["trace[726043447] 'agreement among raft nodes before linearized reading' (duration: 549.200289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:57.823257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:57.273403Z","time spent":"549.810751ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-14T09:31:57.823265Z","caller":"traceutil/trace.go:171","msg":"trace[1223045888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2104; }","duration":"481.506355ms","start":"2026-05-14T09:31:57.341684Z","end":"2026-05-14T09:31:57.82319Z","steps":["trace[1223045888] 'agreement among raft nodes before linearized reading' (duration: 480.991755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:57.823398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:57.341665Z","time spent":"481.648007ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T09:31:58.24213Z","caller":"traceutil/trace.go:171","msg":"trace[1645804461] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"414.214263ms","start":"2026-05-14T09:31:57.827879Z","end":"2026-05-14T09:31:58.242093Z","steps":["trace[1645804461] 'process raft request' (duration: 346.949892ms)","trace[1645804461] 'compare' (duration: 67.136529ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:31:58.242365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:57.827857Z","time spent":"414.386985ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:31:58.244419Z","caller":"traceutil/trace.go:171","msg":"trace[40262409] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"415.664616ms","start":"2026-05-14T09:31:57.828723Z","end":"2026-05-14T09:31:58.244388Z","steps":["trace[40262409] 'process raft request' (duration: 415.581884ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:31:58.244653Z","caller":"traceutil/trace.go:171","msg":"trace[794392059] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"124.185046ms","start":"2026-05-14T09:31:58.120451Z","end":"2026-05-14T09:31:58.244636Z","steps":["trace[794392059] 'process raft request' (duration: 123.895431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:31:58.244705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:57.828714Z","time spent":"415.787958ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:31:58.244937Z","caller":"traceutil/trace.go:171","msg":"trace[1727476326] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"416.516791ms","start":"2026-05-14T09:31:57.828378Z","end":"2026-05-14T09:31:58.244895Z","steps":["trace[1727476326] 'process raft request' (duration: 415.800879ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:31:58.245001Z","caller":"traceutil/trace.go:171","msg":"trace[41121274] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"124.40895ms","start":"2026-05-14T09:31:58.120578Z","end":"2026-05-14T09:31:58.244987Z","steps":["trace[41121274] 'process raft request' (duration: 124.004893ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:31:58.244995Z","caller":"traceutil/trace.go:171","msg":"trace[407256481] linearizableReadLoop","detail":"{readStateIndex:2185; appliedIndex:2182; }","duration":"214.607789ms","start":"2026-05-14T09:31:58.030365Z","end":"2026-05-14T09:31:58.244973Z","steps":["trace[407256481] 'read index received' (duration: 144.429379ms)","trace[407256481] 'applied index is now lower than readState.Index' (duration: 70.17714ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:31:58.24514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:31:57.828354Z","time spent":"416.674714ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:31:58.245223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.819173ms","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-05-14T09:31:58.245267Z","caller":"traceutil/trace.go:171","msg":"trace[541702192] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2109; }","duration":"214.953395ms","start":"2026-05-14T09:31:58.030303Z","end":"2026-05-14T09:31:58.245257Z","steps":["trace[541702192] 'agreement among raft nodes before linearized reading' (duration: 214.738212ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:33:22.056516Z","caller":"traceutil/trace.go:171","msg":"trace[1087065127] transaction","detail":"{read_only:false; response_revision:2596; number_of_response:1; }","duration":"313.363636ms","start":"2026-05-14T09:33:21.743113Z","end":"2026-05-14T09:33:22.056477Z","steps":["trace[1087065127] 'process raft request' (duration: 310.660294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:33:22.056723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:33:21.743088Z","time spent":"313.535678ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:33:32.39688Z","caller":"traceutil/trace.go:171","msg":"trace[2021402988] transaction","detail":"{read_only:false; response_revision:2703; number_of_response:1; }","duration":"303.469412ms","start":"2026-05-14T09:33:32.093371Z","end":"2026-05-14T09:33:32.39684Z","steps":["trace[2021402988] 'process raft request' (duration: 303.218909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:33:32.397134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:33:32.093343Z","time spent":"303.653545ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:33:32.404394Z","caller":"traceutil/trace.go:171","msg":"trace[732965857] linearizableReadLoop","detail":"{readStateIndex:2804; appliedIndex:2802; }","duration":"106.776674ms","start":"2026-05-14T09:33:32.297592Z","end":"2026-05-14T09:33:32.404368Z","steps":["trace[732965857] 'read index received' (duration: 99.042083ms)","trace[732965857] 'applied index is now lower than readState.Index' (duration: 7.733481ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:33:32.404459Z","caller":"traceutil/trace.go:171","msg":"trace[193773995] transaction","detail":"{read_only:false; response_revision:2704; number_of_response:1; }","duration":"152.250401ms","start":"2026-05-14T09:33:32.252176Z","end":"2026-05-14T09:33:32.404426Z","steps":["trace[193773995] 'process raft request' (duration: 151.054468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:33:32.404608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.985006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:33:32.404697Z","caller":"traceutil/trace.go:171","msg":"trace[1624977945] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2705; }","duration":"107.156668ms","start":"2026-05-14T09:33:32.29752Z","end":"2026-05-14T09:33:32.404677Z","steps":["trace[1624977945] 'agreement among raft nodes before linearized reading' (duration: 106.996526ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:03.537817Z","caller":"traceutil/trace.go:171","msg":"trace[1660577201] linearizableReadLoop","detail":"{readStateIndex:2986; appliedIndex:2985; }","duration":"225.112633ms","start":"2026-05-14T09:34:03.312676Z","end":"2026-05-14T09:34:03.537788Z","steps":["trace[1660577201] 'read index received' (duration: 224.959491ms)","trace[1660577201] 'applied index is now lower than readState.Index' (duration: 151.942µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:34:03.537922Z","caller":"traceutil/trace.go:171","msg":"trace[1436900068] transaction","detail":"{read_only:false; response_revision:2880; number_of_response:1; }","duration":"315.558472ms","start":"2026-05-14T09:34:03.222298Z","end":"2026-05-14T09:34:03.537856Z","steps":["trace[1436900068] 'process raft request' (duration: 315.358049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:03.538024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.337665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:34:03.538176Z","caller":"traceutil/trace.go:171","msg":"trace[1313387158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2880; }","duration":"225.560078ms","start":"2026-05-14T09:34:03.312603Z","end":"2026-05-14T09:34:03.538163Z","steps":["trace[1313387158] 'agreement among raft nodes before linearized reading' (duration: 225.339726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:03.538344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:03.222271Z","time spent":"315.886965ms","remote":"127.0.0.1:33380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:34:03.538344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.603239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-14T09:34:03.538531Z","caller":"traceutil/trace.go:171","msg":"trace[239340248] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2880; }","duration":"145.840471ms","start":"2026-05-14T09:34:03.392648Z","end":"2026-05-14T09:34:03.538489Z","steps":["trace[239340248] 'agreement among raft nodes before linearized reading' (duration: 145.394776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:03.857173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.691912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T09:34:03.857332Z","caller":"traceutil/trace.go:171","msg":"trace[1065940001] transaction","detail":"{read_only:false; response_revision:2881; number_of_response:1; }","duration":"313.037253ms","start":"2026-05-14T09:34:03.544273Z","end":"2026-05-14T09:34:03.85731Z","steps":["trace[1065940001] 'process raft request' (duration: 149.107258ms)","trace[1065940001] 'compare' (duration: 163.46233ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:34:03.857395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:03.54425Z","time spent":"313.115254ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:34:04.419331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.701042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T09:34:04.419407Z","caller":"traceutil/trace.go:171","msg":"trace[1970240735] linearizableReadLoop","detail":"{readStateIndex:2988; appliedIndex:2987; }","duration":"372.192673ms","start":"2026-05-14T09:34:04.047202Z","end":"2026-05-14T09:34:04.419394Z","steps":["trace[1970240735] 'read index received' (duration: 134.30509ms)","trace[1970240735] 'applied index is now lower than readState.Index' (duration: 237.886683ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:34:04.419514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.324735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18af64d064e320f9\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-14T09:34:04.419534Z","caller":"traceutil/trace.go:171","msg":"trace[271441051] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18af64d064e320f9; range_end:; response_count:1; response_revision:2882; }","duration":"372.384805ms","start":"2026-05-14T09:34:04.047144Z","end":"2026-05-14T09:34:04.419528Z","steps":["trace[271441051] 'agreement among raft nodes before linearized reading' (duration: 372.281474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:04.419554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:04.047125Z","time spent":"372.425076ms","remote":"127.0.0.1:33466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":803,"request content":"key:\"/registry/events/auth-system/keycloak-0.18af64d064e320f9\" "} {"level":"info","ts":"2026-05-14T09:34:04.41999Z","caller":"traceutil/trace.go:171","msg":"trace[726624167] transaction","detail":"{read_only:false; response_revision:2882; number_of_response:1; }","duration":"499.760527ms","start":"2026-05-14T09:34:03.920182Z","end":"2026-05-14T09:34:04.419943Z","steps":["trace[726624167] 'process raft request' (duration: 261.368968ms)","trace[726624167] 'compare' (duration: 237.446789ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:34:04.42034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:03.920164Z","time spent":"499.9204ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:34:04.534857Z","caller":"traceutil/trace.go:171","msg":"trace[2002182525] transaction","detail":"{read_only:false; response_revision:2883; number_of_response:1; }","duration":"112.725566ms","start":"2026-05-14T09:34:04.422098Z","end":"2026-05-14T09:34:04.534823Z","steps":["trace[2002182525] 'process raft request' (duration: 109.543861ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:29.145104Z","caller":"traceutil/trace.go:171","msg":"trace[2049566180] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18af64d064e320f9; range_end:; response_count:1; response_revision:3012; }","duration":"100.018585ms","start":"2026-05-14T09:34:29.045055Z","end":"2026-05-14T09:34:29.145074Z","steps":["trace[2049566180] 'range keys from in-memory index tree' (duration: 99.690116ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:29.307575Z","caller":"traceutil/trace.go:171","msg":"trace[1256973779] transaction","detail":"{read_only:false; response_revision:3015; number_of_response:1; }","duration":"144.644935ms","start":"2026-05-14T09:34:29.162904Z","end":"2026-05-14T09:34:29.307549Z","steps":["trace[1256973779] 'process raft request' (duration: 86.76616ms)","trace[1256973779] 'compare' (duration: 57.626648ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:34:29.307793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.460722ms","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-05-14T09:34:29.30785Z","caller":"traceutil/trace.go:171","msg":"trace[1752311297] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3015; }","duration":"143.541883ms","start":"2026-05-14T09:34:29.164297Z","end":"2026-05-14T09:34:29.307839Z","steps":["trace[1752311297] 'agreement among raft nodes before linearized reading' (duration: 143.400819ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:29.307644Z","caller":"traceutil/trace.go:171","msg":"trace[2004684356] linearizableReadLoop","detail":"{readStateIndex:3127; appliedIndex:3126; }","duration":"143.109802ms","start":"2026-05-14T09:34:29.164314Z","end":"2026-05-14T09:34:29.307424Z","steps":["trace[2004684356] 'read index received' (duration: 54.551511ms)","trace[2004684356] 'applied index is now lower than readState.Index' (duration: 88.557471ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:34:29.308152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.333668ms","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-05-14T09:34:29.30818Z","caller":"traceutil/trace.go:171","msg":"trace[1115711811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3015; }","duration":"143.374809ms","start":"2026-05-14T09:34:29.164796Z","end":"2026-05-14T09:34:29.308171Z","steps":["trace[1115711811] 'agreement among raft nodes before linearized reading' (duration: 143.289846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:29.308455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.993086ms","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-05-14T09:34:29.308508Z","caller":"traceutil/trace.go:171","msg":"trace[128655119] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:3015; }","duration":"116.114889ms","start":"2026-05-14T09:34:29.192375Z","end":"2026-05-14T09:34:29.30849Z","steps":["trace[128655119] 'agreement among raft nodes before linearized reading' (duration: 116.024126ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:59.856638Z","caller":"traceutil/trace.go:171","msg":"trace[647888402] transaction","detail":"{read_only:false; response_revision:3174; number_of_response:1; }","duration":"346.607932ms","start":"2026-05-14T09:34:59.50999Z","end":"2026-05-14T09:34:59.856598Z","steps":["trace[647888402] 'process raft request' (duration: 346.11711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:59.857515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:59.509972Z","time spent":"346.739836ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:34:59.858156Z","caller":"traceutil/trace.go:171","msg":"trace[1378205316] linearizableReadLoop","detail":"{readStateIndex:3293; appliedIndex:3291; }","duration":"345.441464ms","start":"2026-05-14T09:34:59.512683Z","end":"2026-05-14T09:34:59.858125Z","steps":["trace[1378205316] 'read index received' (duration: 343.442654ms)","trace[1378205316] 'applied index is now lower than readState.Index' (duration: 1.99685ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:34:59.858425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.70628ms","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-05-14T09:34:59.858466Z","caller":"traceutil/trace.go:171","msg":"trace[817971630] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3175; }","duration":"345.796143ms","start":"2026-05-14T09:34:59.51266Z","end":"2026-05-14T09:34:59.858456Z","steps":["trace[817971630] 'agreement among raft nodes before linearized reading' (duration: 345.588168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:59.85852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:59.51265Z","time spent":"345.841864ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-14T09:34:59.858699Z","caller":"traceutil/trace.go:171","msg":"trace[1926942503] transaction","detail":"{read_only:false; response_revision:3175; number_of_response:1; }","duration":"346.794017ms","start":"2026-05-14T09:34:59.511892Z","end":"2026-05-14T09:34:59.858686Z","steps":["trace[1926942503] 'process raft request' (duration: 345.444423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:59.858813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:59.511874Z","time spent":"346.859759ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:35:14.401447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.841741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18af64d064e320f9\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-14T09:35:14.401539Z","caller":"traceutil/trace.go:171","msg":"trace[1944781747] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18af64d064e320f9; range_end:; response_count:1; response_revision:3249; }","duration":"357.034335ms","start":"2026-05-14T09:35:14.044483Z","end":"2026-05-14T09:35:14.401517Z","steps":["trace[1944781747] 'range keys from in-memory index tree' (duration: 356.551835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:14.401594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:14.044434Z","time spent":"357.148378ms","remote":"127.0.0.1:33466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":803,"request content":"key:\"/registry/events/auth-system/keycloak-0.18af64d064e320f9\" "} {"level":"warn","ts":"2026-05-14T09:35:14.401478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.317808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:35:14.402142Z","caller":"traceutil/trace.go:171","msg":"trace[2136083] transaction","detail":"{read_only:false; response_revision:3250; number_of_response:1; }","duration":"160.018433ms","start":"2026-05-14T09:35:14.242098Z","end":"2026-05-14T09:35:14.402116Z","steps":["trace[2136083] 'process raft request' (duration: 154.666137ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:35:14.402327Z","caller":"traceutil/trace.go:171","msg":"trace[1028144573] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:3249; }","duration":"334.208939ms","start":"2026-05-14T09:35:14.068061Z","end":"2026-05-14T09:35:14.40227Z","steps":["trace[1028144573] 'count revisions from in-memory index tree' (duration: 333.224636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:14.402979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:14.068038Z","time spent":"334.918847ms","remote":"127.0.0.1:49146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-14T09:35:18.451617Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3275979664223112871,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T09:35:18.460727Z","caller":"traceutil/trace.go:171","msg":"trace[1677086184] transaction","detail":"{read_only:false; response_revision:3266; number_of_response:1; }","duration":"673.145435ms","start":"2026-05-14T09:35:17.787556Z","end":"2026-05-14T09:35:18.460702Z","steps":["trace[1677086184] 'process raft request' (duration: 672.973731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:18.460896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:17.787534Z","time spent":"673.272088ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:35:18.466059Z","caller":"traceutil/trace.go:171","msg":"trace[1189453579] linearizableReadLoop","detail":"{readStateIndex:3389; appliedIndex:3387; }","duration":"515.190492ms","start":"2026-05-14T09:35:17.950847Z","end":"2026-05-14T09:35:18.466037Z","steps":["trace[1189453579] 'read index received' (duration: 509.697125ms)","trace[1189453579] 'applied index is now lower than readState.Index' (duration: 5.492057ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:35:18.46613Z","caller":"traceutil/trace.go:171","msg":"trace[380987143] transaction","detail":"{read_only:false; response_revision:3267; number_of_response:1; }","duration":"678.09994ms","start":"2026-05-14T09:35:17.788011Z","end":"2026-05-14T09:35:18.466111Z","steps":["trace[380987143] 'process raft request' (duration: 677.894835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:18.466213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:17.787985Z","time spent":"678.184502ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:35:18.46628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.424187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-14T09:35:18.466312Z","caller":"traceutil/trace.go:171","msg":"trace[1401986951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3267; }","duration":"515.507649ms","start":"2026-05-14T09:35:17.950796Z","end":"2026-05-14T09:35:18.466304Z","steps":["trace[1401986951] 'agreement among raft nodes before linearized reading' (duration: 515.355646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:18.466339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:17.950769Z","time spent":"515.56368ms","remote":"127.0.0.1:33622","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":"warn","ts":"2026-05-14T09:35:18.466399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.213544ms","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-05-14T09:35:18.466462Z","caller":"traceutil/trace.go:171","msg":"trace[551990898] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:3267; }","duration":"107.309677ms","start":"2026-05-14T09:35:18.359143Z","end":"2026-05-14T09:35:18.466453Z","steps":["trace[551990898] 'agreement among raft nodes before linearized reading' (duration: 107.202754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:18.466555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.886637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-05-14T09:35:18.466583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.895318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-14T09:35:18.466622Z","caller":"traceutil/trace.go:171","msg":"trace[200312457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3267; }","duration":"509.97314ms","start":"2026-05-14T09:35:17.956635Z","end":"2026-05-14T09:35:18.466608Z","steps":["trace[200312457] 'agreement among raft nodes before linearized reading' (duration: 509.840457ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:35:18.46662Z","caller":"traceutil/trace.go:171","msg":"trace[1211086546] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3267; }","duration":"139.01041ms","start":"2026-05-14T09:35:18.327594Z","end":"2026-05-14T09:35:18.466604Z","steps":["trace[1211086546] 'agreement among raft nodes before linearized reading' (duration: 138.749504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:18.466648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:17.956609Z","time spent":"510.031002ms","remote":"127.0.0.1:33622","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-05-14T09:35:18.466677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.578211ms","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-05-14T09:35:18.466786Z","caller":"traceutil/trace.go:171","msg":"trace[751464875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3267; }","duration":"512.727784ms","start":"2026-05-14T09:35:17.954033Z","end":"2026-05-14T09:35:18.46676Z","steps":["trace[751464875] 'agreement among raft nodes before linearized reading' (duration: 512.318904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:18.46685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:17.954027Z","time spent":"512.808156ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-14T09:36:15.298684Z","caller":"traceutil/trace.go:171","msg":"trace[2069672937] transaction","detail":"{read_only:false; response_revision:3646; number_of_response:1; }","duration":"308.688869ms","start":"2026-05-14T09:36:14.98996Z","end":"2026-05-14T09:36:15.298649Z","steps":["trace[2069672937] 'process raft request' (duration: 299.277258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:15.298861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:14.989933Z","time spent":"308.842352ms","remote":"127.0.0.1:33542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9479,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:36:17.345403Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3275979664223113842,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T09:36:17.375293Z","caller":"traceutil/trace.go:171","msg":"trace[782830872] linearizableReadLoop","detail":"{readStateIndex:3791; appliedIndex:3790; }","duration":"529.998253ms","start":"2026-05-14T09:36:16.845263Z","end":"2026-05-14T09:36:17.375261Z","steps":["trace[782830872] 'read index received' (duration: 529.762199ms)","trace[782830872] 'applied index is now lower than readState.Index' (duration: 234.944µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:36:17.37541Z","caller":"traceutil/trace.go:171","msg":"trace[104464106] transaction","detail":"{read_only:false; response_revision:3653; number_of_response:1; }","duration":"564.509927ms","start":"2026-05-14T09:36:16.810873Z","end":"2026-05-14T09:36:17.375383Z","steps":["trace[104464106] 'process raft request' (duration: 564.163471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:17.375619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:16.810832Z","time spent":"564.63988ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:36:17.375656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.35309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-14T09:36:17.37575Z","caller":"traceutil/trace.go:171","msg":"trace[1685005789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3653; }","duration":"530.490553ms","start":"2026-05-14T09:36:16.845236Z","end":"2026-05-14T09:36:17.375726Z","steps":["trace[1685005789] 'agreement among raft nodes before linearized reading' (duration: 530.178867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:17.375795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:16.845223Z","time spent":"530.564284ms","remote":"127.0.0.1:33622","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":"warn","ts":"2026-05-14T09:36:17.411263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.767059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-14T09:36:17.411355Z","caller":"traceutil/trace.go:171","msg":"trace[1176482844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3655; }","duration":"539.901192ms","start":"2026-05-14T09:36:16.871435Z","end":"2026-05-14T09:36:17.411336Z","steps":["trace[1176482844] 'agreement among raft nodes before linearized reading' (duration: 539.620157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:17.411396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.908747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-14T09:36:17.411406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.731833ms","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":"warn","ts":"2026-05-14T09:36:17.411438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:16.871424Z","time spent":"539.959353ms","remote":"127.0.0.1:33622","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":"info","ts":"2026-05-14T09:36:17.411453Z","caller":"traceutil/trace.go:171","msg":"trace[752568828] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:3655; }","duration":"228.974588ms","start":"2026-05-14T09:36:17.182464Z","end":"2026-05-14T09:36:17.411438Z","steps":["trace[752568828] 'agreement among raft nodes before linearized reading' (duration: 228.877966ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:17.411484Z","caller":"traceutil/trace.go:171","msg":"trace[2024137673] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3655; }","duration":"483.871026ms","start":"2026-05-14T09:36:16.927598Z","end":"2026-05-14T09:36:17.411469Z","steps":["trace[2024137673] 'agreement among raft nodes before linearized reading' (duration: 483.485589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:17.41152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:16.927544Z","time spent":"483.967028ms","remote":"127.0.0.1:33622","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-05-14T09:36:17.411834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.934003ms","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-05-14T09:36:17.411878Z","caller":"traceutil/trace.go:171","msg":"trace[1136760160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3655; }","duration":"513.036455ms","start":"2026-05-14T09:36:16.898831Z","end":"2026-05-14T09:36:17.411868Z","steps":["trace[1136760160] 'agreement among raft nodes before linearized reading' (duration: 512.890872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:17.411952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:16.898799Z","time spent":"513.143968ms","remote":"127.0.0.1:33622","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-05-14T09:36:17.412129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.573232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:36:17.412461Z","caller":"traceutil/trace.go:171","msg":"trace[353100064] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:3655; }","duration":"226.927648ms","start":"2026-05-14T09:36:17.18552Z","end":"2026-05-14T09:36:17.412448Z","steps":["trace[353100064] 'agreement among raft nodes before linearized reading' (duration: 226.356297ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:34.13226Z","caller":"traceutil/trace.go:171","msg":"trace[1139509635] transaction","detail":"{read_only:false; response_revision:3754; number_of_response:1; }","duration":"155.594371ms","start":"2026-05-14T09:36:33.976629Z","end":"2026-05-14T09:36:34.132224Z","steps":["trace[1139509635] 'process raft request' (duration: 155.324875ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:34.783932Z","caller":"traceutil/trace.go:171","msg":"trace[624786807] linearizableReadLoop","detail":"{readStateIndex:3896; appliedIndex:3895; }","duration":"184.181653ms","start":"2026-05-14T09:36:34.599722Z","end":"2026-05-14T09:36:34.783903Z","steps":["trace[624786807] 'read index received' (duration: 184.03899ms)","trace[624786807] 'applied index is now lower than readState.Index' (duration: 141.103µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:36:34.784145Z","caller":"traceutil/trace.go:171","msg":"trace[2045844103] transaction","detail":"{read_only:false; response_revision:3755; number_of_response:1; }","duration":"192.407433ms","start":"2026-05-14T09:36:34.591722Z","end":"2026-05-14T09:36:34.78413Z","steps":["trace[2045844103] 'process raft request' (duration: 191.986165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:34.784668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.711313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T09:36:34.784696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.839724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-client-secret\" ","response":"range_response_count:1 size:1225"} {"level":"info","ts":"2026-05-14T09:36:34.78476Z","caller":"traceutil/trace.go:171","msg":"trace[1016846509] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:3755; }","duration":"142.866026ms","start":"2026-05-14T09:36:34.641873Z","end":"2026-05-14T09:36:34.784739Z","steps":["trace[1016846509] 'agreement among raft nodes before linearized reading' (duration: 142.643212ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:34.784771Z","caller":"traceutil/trace.go:171","msg":"trace[1608882043] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-client-secret; range_end:; response_count:1; response_revision:3755; }","duration":"185.104629ms","start":"2026-05-14T09:36:34.599646Z","end":"2026-05-14T09:36:34.78475Z","steps":["trace[1608882043] 'agreement among raft nodes before linearized reading' (duration: 184.786673ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:37:57.796186Z","caller":"traceutil/trace.go:171","msg":"trace[1051294918] transaction","detail":"{read_only:false; response_revision:4612; number_of_response:1; }","duration":"116.749595ms","start":"2026-05-14T09:37:57.679408Z","end":"2026-05-14T09:37:57.796158Z","steps":["trace[1051294918] 'process raft request' (duration: 114.347657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:00.198527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.285139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2195922"} {"level":"info","ts":"2026-05-14T09:38:00.198683Z","caller":"traceutil/trace.go:171","msg":"trace[2048060850] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4725; }","duration":"142.504622ms","start":"2026-05-14T09:38:00.056145Z","end":"2026-05-14T09:38:00.19865Z","steps":["trace[2048060850] 'range keys from in-memory index tree' (duration: 140.544472ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:38:06.617364Z","caller":"traceutil/trace.go:171","msg":"trace[1378560816] linearizableReadLoop","detail":"{readStateIndex:5046; appliedIndex:5044; }","duration":"136.877647ms","start":"2026-05-14T09:38:06.480451Z","end":"2026-05-14T09:38:06.617329Z","steps":["trace[1378560816] 'read index received' (duration: 105.903194ms)","trace[1378560816] 'applied index is now lower than readState.Index' (duration: 30.973203ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:38:06.617624Z","caller":"traceutil/trace.go:171","msg":"trace[1919030057] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"138.655553ms","start":"2026-05-14T09:38:06.47892Z","end":"2026-05-14T09:38:06.617575Z","steps":["trace[1919030057] 'process raft request' (duration: 107.417007ms)","trace[1919030057] 'compare' (duration: 30.642547ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:38:06.617767Z","caller":"traceutil/trace.go:171","msg":"trace[2129544868] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"138.650083ms","start":"2026-05-14T09:38:06.479018Z","end":"2026-05-14T09:38:06.617668Z","steps":["trace[2129544868] 'process raft request' (duration: 138.197226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:06.617833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.316363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-05-14T09:38:06.617964Z","caller":"traceutil/trace.go:171","msg":"trace[1420902684] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4861; }","duration":"137.528507ms","start":"2026-05-14T09:38:06.480417Z","end":"2026-05-14T09:38:06.617946Z","steps":["trace[1420902684] 'agreement among raft nodes before linearized reading' (duration: 137.153801ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:38:13.231493Z","caller":"traceutil/trace.go:171","msg":"trace[1172192488] linearizableReadLoop","detail":"{readStateIndex:5146; appliedIndex:5145; }","duration":"254.850587ms","start":"2026-05-14T09:38:12.976595Z","end":"2026-05-14T09:38:13.231446Z","steps":["trace[1172192488] 'read index received' (duration: 254.644404ms)","trace[1172192488] 'applied index is now lower than readState.Index' (duration: 204.693µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:38:13.231666Z","caller":"traceutil/trace.go:171","msg":"trace[1636190917] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"354.547813ms","start":"2026-05-14T09:38:12.877106Z","end":"2026-05-14T09:38:13.231654Z","steps":["trace[1636190917] 'process raft request' (duration: 354.058356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:13.231838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:38:12.877078Z","time spent":"354.628395ms","remote":"127.0.0.1:33466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":997,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:38:13.231954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.339354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3c469925-bef2-4c5c-b355-da32be33cd0d\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-14T09:38:13.232048Z","caller":"traceutil/trace.go:171","msg":"trace[1806990540] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3c469925-bef2-4c5c-b355-da32be33cd0d; range_end:; response_count:1; response_revision:4960; }","duration":"255.474095ms","start":"2026-05-14T09:38:12.976552Z","end":"2026-05-14T09:38:13.232026Z","steps":["trace[1806990540] 'agreement among raft nodes before linearized reading' (duration: 255.252352ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:38:13.615524Z","caller":"traceutil/trace.go:171","msg":"trace[1622786608] linearizableReadLoop","detail":"{readStateIndex:5147; appliedIndex:5146; }","duration":"383.891703ms","start":"2026-05-14T09:38:13.231601Z","end":"2026-05-14T09:38:13.615493Z","steps":["trace[1622786608] 'read index received' (duration: 352.860665ms)","trace[1622786608] 'applied index is now lower than readState.Index' (duration: 31.029948ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:38:13.615544Z","caller":"traceutil/trace.go:171","msg":"trace[1696583154] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"489.260283ms","start":"2026-05-14T09:38:13.126236Z","end":"2026-05-14T09:38:13.615496Z","steps":["trace[1696583154] 'process raft request' (duration: 458.330037ms)","trace[1696583154] 'compare' (duration: 30.604872ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:38:13.615685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:38:13.126217Z","time spent":"489.392325ms","remote":"127.0.0.1:33466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":877,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T09:38:13.615755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.980534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-cd9b9dd8-8fw6g\" ","response":"range_response_count:1 size:11246"} {"level":"info","ts":"2026-05-14T09:38:13.615781Z","caller":"traceutil/trace.go:171","msg":"trace[1769786060] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-cd9b9dd8-8fw6g; range_end:; response_count:1; response_revision:4961; }","duration":"490.065375ms","start":"2026-05-14T09:38:13.125707Z","end":"2026-05-14T09:38:13.615772Z","steps":["trace[1769786060] 'agreement among raft nodes before linearized reading' (duration: 489.910523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:13.615811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:38:13.125679Z","time spent":"490.126326ms","remote":"127.0.0.1:33554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":11268,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-cd9b9dd8-8fw6g\" "} {"level":"warn","ts":"2026-05-14T09:38:13.615942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.373368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:38:13.615989Z","caller":"traceutil/trace.go:171","msg":"trace[1580372626] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4961; }","duration":"303.485889ms","start":"2026-05-14T09:38:13.312493Z","end":"2026-05-14T09:38:13.615979Z","steps":["trace[1580372626] 'agreement among raft nodes before linearized reading' (duration: 303.402438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:13.616018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:38:13.312467Z","time spent":"303.54612ms","remote":"127.0.0.1:33370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T09:38:13.862074Z","caller":"traceutil/trace.go:171","msg":"trace[863695318] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"233.822688ms","start":"2026-05-14T09:38:13.628231Z","end":"2026-05-14T09:38:13.862054Z","steps":["trace[863695318] 'process raft request' (duration: 233.673626ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:38:14.033627Z","caller":"traceutil/trace.go:171","msg":"trace[348264489] transaction","detail":"{read_only:false; response_revision:4966; number_of_response:1; }","duration":"105.42543ms","start":"2026-05-14T09:38:13.928168Z","end":"2026-05-14T09:38:14.033594Z","steps":["trace[348264489] 'process raft request' (duration: 17.575505ms)","trace[348264489] 'compare' (duration: 87.168925ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:38:14.492689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.595149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T09:38:14.492906Z","caller":"traceutil/trace.go:171","msg":"trace[1562965686] linearizableReadLoop","detail":"{readStateIndex:5158; appliedIndex:5156; }","duration":"395.782144ms","start":"2026-05-14T09:38:14.097108Z","end":"2026-05-14T09:38:14.49289Z","steps":["trace[1562965686] 'read index received' (duration: 248.792019ms)","trace[1562965686] 'applied index is now lower than readState.Index' (duration: 146.989054ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:38:14.493037Z","caller":"traceutil/trace.go:171","msg":"trace[1485962451] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"409.708093ms","start":"2026-05-14T09:38:14.083298Z","end":"2026-05-14T09:38:14.493006Z","steps":["trace[1485962451] 'process raft request' (duration: 262.677638ms)","trace[1485962451] 'compare' (duration: 146.294644ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:38:14.49304Z","caller":"traceutil/trace.go:171","msg":"trace[59340594] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"409.675293ms","start":"2026-05-14T09:38:14.083331Z","end":"2026-05-14T09:38:14.493006Z","steps":["trace[59340594] 'process raft request' (duration: 409.47096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:14.493122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.152944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:38:14.493156Z","caller":"traceutil/trace.go:171","msg":"trace[1118787206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4972; }","duration":"166.214215ms","start":"2026-05-14T09:38:14.32693Z","end":"2026-05-14T09:38:14.493144Z","steps":["trace[1118787206] 'agreement among raft nodes before linearized reading' (duration: 166.155904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:14.493218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:38:14.083279Z","time spent":"409.821466ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:38:14.493166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.059227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"warn","ts":"2026-05-14T09:38:14.493257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:38:14.083309Z","time spent":"409.835485ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:38:14.49328Z","caller":"traceutil/trace.go:171","msg":"trace[616684963] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4972; }","duration":"396.21418ms","start":"2026-05-14T09:38:14.097048Z","end":"2026-05-14T09:38:14.493262Z","steps":["trace[616684963] 'agreement among raft nodes before linearized reading' (duration: 395.944236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:14.493259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.753366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-cd9b9dd8-8fw6g.18af650f0b38e51c\" ","response":"range_response_count:1 size:892"} {"level":"warn","ts":"2026-05-14T09:38:14.493321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:38:14.097027Z","time spent":"396.285651ms","remote":"127.0.0.1:33558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1287,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" "} {"level":"info","ts":"2026-05-14T09:38:14.493342Z","caller":"traceutil/trace.go:171","msg":"trace[1398734993] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-cd9b9dd8-8fw6g.18af650f0b38e51c; range_end:; response_count:1; response_revision:4972; }","duration":"358.882938ms","start":"2026-05-14T09:38:14.134437Z","end":"2026-05-14T09:38:14.49332Z","steps":["trace[1398734993] 'agreement among raft nodes before linearized reading' (duration: 358.652174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:14.4934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:38:14.134417Z","time spent":"358.970089ms","remote":"127.0.0.1:33466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":1,"response size":914,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-cd9b9dd8-8fw6g.18af650f0b38e51c\" "} {"level":"warn","ts":"2026-05-14T09:38:14.493427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.962302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3c469925-bef2-4c5c-b355-da32be33cd0d\" ","response":"range_response_count:1 size:3762"} {"level":"info","ts":"2026-05-14T09:38:14.493559Z","caller":"traceutil/trace.go:171","msg":"trace[2099187299] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3c469925-bef2-4c5c-b355-da32be33cd0d; range_end:; response_count:1; response_revision:4972; }","duration":"257.140494ms","start":"2026-05-14T09:38:14.23638Z","end":"2026-05-14T09:38:14.493521Z","steps":["trace[2099187299] 'agreement among raft nodes before linearized reading' (duration: 256.764418ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:38:14.791916Z","caller":"traceutil/trace.go:171","msg":"trace[490204279] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"106.410083ms","start":"2026-05-14T09:38:14.685471Z","end":"2026-05-14T09:38:14.791881Z","steps":["trace[490204279] 'process raft request' (duration: 106.21017ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:38:14.791945Z","caller":"traceutil/trace.go:171","msg":"trace[115607709] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"106.85016ms","start":"2026-05-14T09:38:14.685065Z","end":"2026-05-14T09:38:14.791915Z","steps":["trace[115607709] 'process raft request' (duration: 78.445232ms)","trace[115607709] 'compare' (duration: 27.92216ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:38:15.139387Z","caller":"traceutil/trace.go:171","msg":"trace[2058524133] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"139.942716ms","start":"2026-05-14T09:38:14.999415Z","end":"2026-05-14T09:38:15.139358Z","steps":["trace[2058524133] 'process raft request' (duration: 139.781164ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:38:18.732866Z","caller":"traceutil/trace.go:171","msg":"trace[970523986] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"144.932252ms","start":"2026-05-14T09:38:18.587888Z","end":"2026-05-14T09:38:18.73282Z","steps":["trace[970523986] 'process raft request' (duration: 144.105239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:28.965066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.163661ms","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-05-14T09:38:28.965222Z","caller":"traceutil/trace.go:171","msg":"trace[2129087405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5167; }","duration":"171.355144ms","start":"2026-05-14T09:38:28.793818Z","end":"2026-05-14T09:38:28.965173Z","steps":["trace[2129087405] 'range keys from in-memory index tree' (duration: 170.924257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:28.965776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.42994ms","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-05-14T09:38:28.965822Z","caller":"traceutil/trace.go:171","msg":"trace[1538241498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5167; }","duration":"402.511372ms","start":"2026-05-14T09:38:28.5633Z","end":"2026-05-14T09:38:28.965811Z","steps":["trace[1538241498] 'range keys from in-memory index tree' (duration: 402.310609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:28.965856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:38:28.563276Z","time spent":"402.571373ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T09:38:28.966083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.29967ms","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-05-14T09:38:28.966124Z","caller":"traceutil/trace.go:171","msg":"trace[372312764] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5167; }","duration":"164.387012ms","start":"2026-05-14T09:38:28.801727Z","end":"2026-05-14T09:38:28.966114Z","steps":["trace[372312764] 'range keys from in-memory index tree' (duration: 164.130917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:28.966207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.903847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T09:38:28.966234Z","caller":"traceutil/trace.go:171","msg":"trace[1875091633] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:5167; }","duration":"383.932447ms","start":"2026-05-14T09:38:28.582292Z","end":"2026-05-14T09:38:28.966224Z","steps":["trace[1875091633] 'count revisions from in-memory index tree' (duration: 383.837886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:28.96626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:38:28.582255Z","time spent":"383.997178ms","remote":"127.0.0.1:57288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"info","ts":"2026-05-14T09:38:28.966469Z","caller":"traceutil/trace.go:171","msg":"trace[1804870195] transaction","detail":"{read_only:false; response_revision:5168; number_of_response:1; }","duration":"141.173468ms","start":"2026-05-14T09:38:28.825266Z","end":"2026-05-14T09:38:28.966439Z","steps":["trace[1804870195] 'process raft request' (duration: 94.044261ms)","trace[1804870195] 'compare' (duration: 45.501463ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:38:28.967661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.167529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-14T09:38:28.967727Z","caller":"traceutil/trace.go:171","msg":"trace[84965057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5167; }","duration":"394.729357ms","start":"2026-05-14T09:38:28.572979Z","end":"2026-05-14T09:38:28.967709Z","steps":["trace[84965057] 'range keys from in-memory index tree' (duration: 391.814672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:28.967766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:38:28.572961Z","time spent":"394.793277ms","remote":"127.0.0.1:33622","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-05-14T09:38:29.465093Z","caller":"traceutil/trace.go:171","msg":"trace[1356612509] transaction","detail":"{read_only:false; response_revision:5169; number_of_response:1; }","duration":"491.652321ms","start":"2026-05-14T09:38:28.973363Z","end":"2026-05-14T09:38:29.465016Z","steps":["trace[1356612509] 'process raft request' (duration: 471.116097ms)","trace[1356612509] 'compare' (duration: 19.992556ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:38:29.470291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:38:28.973328Z","time spent":"496.864957ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:38:29.484965Z","caller":"traceutil/trace.go:171","msg":"trace[1785906417] transaction","detail":"{read_only:false; response_revision:5170; number_of_response:1; }","duration":"511.544244ms","start":"2026-05-14T09:38:28.973405Z","end":"2026-05-14T09:38:29.484949Z","steps":["trace[1785906417] 'process raft request' (duration: 511.386761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:29.485105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:38:28.973383Z","time spent":"511.644306ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:38:29.485305Z","caller":"traceutil/trace.go:171","msg":"trace[258499119] transaction","detail":"{read_only:false; response_revision:5171; number_of_response:1; }","duration":"511.746796ms","start":"2026-05-14T09:38:28.973532Z","end":"2026-05-14T09:38:29.485278Z","steps":["trace[258499119] 'process raft request' (duration: 511.386431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:29.485457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:38:28.973526Z","time spent":"511.843719ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:38:29.485596Z","caller":"traceutil/trace.go:171","msg":"trace[301885531] transaction","detail":"{read_only:false; response_revision:5172; number_of_response:1; }","duration":"511.984561ms","start":"2026-05-14T09:38:28.973599Z","end":"2026-05-14T09:38:29.485584Z","steps":["trace[301885531] 'process raft request' (duration: 511.568575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:29.485669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:38:28.973572Z","time spent":"512.072482ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:38:29.485777Z","caller":"traceutil/trace.go:171","msg":"trace[976013119] linearizableReadLoop","detail":"{readStateIndex:5366; appliedIndex:5361; }","duration":"192.600943ms","start":"2026-05-14T09:38:29.293167Z","end":"2026-05-14T09:38:29.485768Z","steps":["trace[976013119] 'read index received' (duration: 151.284494ms)","trace[976013119] 'applied index is now lower than readState.Index' (duration: 41.315469ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:38:29.485905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.743075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:38:29.485945Z","caller":"traceutil/trace.go:171","msg":"trace[2056240805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5173; }","duration":"192.820667ms","start":"2026-05-14T09:38:29.293116Z","end":"2026-05-14T09:38:29.485937Z","steps":["trace[2056240805] 'agreement among raft nodes before linearized reading' (duration: 192.743236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:29.485969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.43879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:38:29.485999Z","caller":"traceutil/trace.go:171","msg":"trace[1891744328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5173; }","duration":"156.491141ms","start":"2026-05-14T09:38:29.329499Z","end":"2026-05-14T09:38:29.485991Z","steps":["trace[1891744328] 'agreement among raft nodes before linearized reading' (duration: 156.44325ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:38:29.486067Z","caller":"traceutil/trace.go:171","msg":"trace[105365060] transaction","detail":"{read_only:false; response_revision:5173; number_of_response:1; }","duration":"512.353616ms","start":"2026-05-14T09:38:28.973704Z","end":"2026-05-14T09:38:29.486058Z","steps":["trace[105365060] 'process raft request' (duration: 511.846218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:38:29.486109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:38:28.973693Z","time spent":"512.393076ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:38:29.840902Z","caller":"traceutil/trace.go:171","msg":"trace[1144628639] transaction","detail":"{read_only:false; response_revision:5175; number_of_response:1; }","duration":"101.838453ms","start":"2026-05-14T09:38:29.739039Z","end":"2026-05-14T09:38:29.840877Z","steps":["trace[1144628639] 'process raft request' (duration: 91.964558ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:39:03.546339Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2880} {"level":"info","ts":"2026-05-14T09:39:03.65294Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2880,"took":"105.262785ms","hash":2875914195,"current-db-size-bytes":52801536,"current-db-size":"53 MB","current-db-size-in-use-bytes":28307456,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-14T09:39:03.653005Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2875914195,"revision":2880,"compact-revision":-1} {"level":"info","ts":"2026-05-14T09:39:05.803731Z","caller":"traceutil/trace.go:171","msg":"trace[263680019] linearizableReadLoop","detail":"{readStateIndex:5684; appliedIndex:5683; }","duration":"108.832323ms","start":"2026-05-14T09:39:05.694867Z","end":"2026-05-14T09:39:05.8037Z","steps":["trace[263680019] 'read index received' (duration: 78.365421ms)","trace[263680019] 'applied index is now lower than readState.Index' (duration: 30.465372ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:39:05.803856Z","caller":"traceutil/trace.go:171","msg":"trace[1352001921] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"110.215493ms","start":"2026-05-14T09:39:05.693615Z","end":"2026-05-14T09:39:05.80383Z","steps":["trace[1352001921] 'process raft request' (duration: 79.475747ms)","trace[1352001921] 'compare' (duration: 30.396031ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:39:05.804077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.181958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-14T09:39:05.804134Z","caller":"traceutil/trace.go:171","msg":"trace[5722764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5481; }","duration":"109.294499ms","start":"2026-05-14T09:39:05.694827Z","end":"2026-05-14T09:39:05.804122Z","steps":["trace[5722764] 'agreement among raft nodes before linearized reading' (duration: 109.053555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:39:05.804267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.264688ms","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-05-14T09:39:05.804307Z","caller":"traceutil/trace.go:171","msg":"trace[396834264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5481; }","duration":"109.36741ms","start":"2026-05-14T09:39:05.69493Z","end":"2026-05-14T09:39:05.804297Z","steps":["trace[396834264] 'agreement among raft nodes before linearized reading' (duration: 109.222888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:39:05.804439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.837213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-14T09:39:05.804475Z","caller":"traceutil/trace.go:171","msg":"trace[899217207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5481; }","duration":"108.902694ms","start":"2026-05-14T09:39:05.695565Z","end":"2026-05-14T09:39:05.804468Z","steps":["trace[899217207] 'agreement among raft nodes before linearized reading' (duration: 108.814292ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:42:21.398851Z","caller":"traceutil/trace.go:171","msg":"trace[1410110323] transaction","detail":"{read_only:false; response_revision:6821; number_of_response:1; }","duration":"134.724387ms","start":"2026-05-14T09:42:21.264096Z","end":"2026-05-14T09:42:21.398821Z","steps":["trace[1410110323] 'process raft request' (duration: 134.584294ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:42:21.429435Z","caller":"traceutil/trace.go:171","msg":"trace[1275891755] transaction","detail":"{read_only:false; response_revision:6822; number_of_response:1; }","duration":"157.466136ms","start":"2026-05-14T09:42:21.271929Z","end":"2026-05-14T09:42:21.429395Z","steps":["trace[1275891755] 'process raft request' (duration: 157.094321ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:42:21.429907Z","caller":"traceutil/trace.go:171","msg":"trace[2030964767] transaction","detail":"{read_only:false; response_revision:6823; number_of_response:1; }","duration":"157.201513ms","start":"2026-05-14T09:42:21.272687Z","end":"2026-05-14T09:42:21.429888Z","steps":["trace[2030964767] 'process raft request' (duration: 156.753176ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:42:21.430171Z","caller":"traceutil/trace.go:171","msg":"trace[173624559] transaction","detail":"{read_only:false; response_revision:6824; number_of_response:1; }","duration":"156.934819ms","start":"2026-05-14T09:42:21.273226Z","end":"2026-05-14T09:42:21.43016Z","steps":["trace[173624559] 'process raft request' (duration: 156.570094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:42:21.493856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.968645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:42:21.494074Z","caller":"traceutil/trace.go:171","msg":"trace[369633691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6824; }","duration":"181.222119ms","start":"2026-05-14T09:42:21.312794Z","end":"2026-05-14T09:42:21.494016Z","steps":["trace[369633691] 'agreement among raft nodes before linearized reading' (duration: 180.962045ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:42:21.493725Z","caller":"traceutil/trace.go:171","msg":"trace[2082466322] linearizableReadLoop","detail":"{readStateIndex:7072; appliedIndex:7069; }","duration":"180.747032ms","start":"2026-05-14T09:42:21.312839Z","end":"2026-05-14T09:42:21.493586Z","steps":["trace[2082466322] 'read index received' (duration: 86.063327ms)","trace[2082466322] 'applied index is now lower than readState.Index' (duration: 94.681885ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:42:45.086068Z","caller":"traceutil/trace.go:171","msg":"trace[916815912] transaction","detail":"{read_only:false; response_revision:6991; number_of_response:1; }","duration":"167.516333ms","start":"2026-05-14T09:42:44.918515Z","end":"2026-05-14T09:42:45.086031Z","steps":["trace[916815912] 'process raft request' (duration: 167.007857ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:42:45.20575Z","caller":"traceutil/trace.go:171","msg":"trace[52322719] transaction","detail":"{read_only:false; response_revision:6992; number_of_response:1; }","duration":"115.421732ms","start":"2026-05-14T09:42:45.09031Z","end":"2026-05-14T09:42:45.205732Z","steps":["trace[52322719] 'process raft request' (duration: 114.881336ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:42:45.65127Z","caller":"traceutil/trace.go:171","msg":"trace[961074881] transaction","detail":"{read_only:false; response_revision:6998; number_of_response:1; }","duration":"136.900455ms","start":"2026-05-14T09:42:45.514341Z","end":"2026-05-14T09:42:45.651242Z","steps":["trace[961074881] 'process raft request' (duration: 136.809004ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:42:45.651644Z","caller":"traceutil/trace.go:171","msg":"trace[381885706] transaction","detail":"{read_only:false; response_revision:6999; number_of_response:1; }","duration":"137.026516ms","start":"2026-05-14T09:42:45.514605Z","end":"2026-05-14T09:42:45.651632Z","steps":["trace[381885706] 'process raft request' (duration: 136.597091ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:42:45.651321Z","caller":"traceutil/trace.go:171","msg":"trace[233969714] transaction","detail":"{read_only:false; response_revision:6997; number_of_response:1; }","duration":"140.893165ms","start":"2026-05-14T09:42:45.510398Z","end":"2026-05-14T09:42:45.651292Z","steps":["trace[233969714] 'process raft request' (duration: 107.487452ms)","trace[233969714] 'compare' (duration: 33.15131ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:44:03.55464Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5462} {"level":"info","ts":"2026-05-14T09:44:03.676062Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5462,"took":"120.232436ms","hash":3360859231,"current-db-size-bytes":52801536,"current-db-size":"53 MB","current-db-size-in-use-bytes":18436096,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-14T09:44:03.676146Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3360859231,"revision":5462,"compact-revision":2880}