{"level":"warn","ts":"2026-04-02T17:46:08.607135Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T17:46:08.607232Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-02T17:46:08.607305Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T17:46:08.607322Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-04-02T17:46:08.60734Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-02T17:46:08.608166Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-04-02T17:46:08.608252Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-02T17:46:08.613255Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.690659ms"} {"level":"info","ts":"2026-04-02T17:46:08.622164Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-04-02T17:46:08.622261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T17:46:08.622289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-04-02T17:46:08.622303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T17:46:08.622319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-04-02T17:46:08.622359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-04-02T17:46:08.629541Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T17:46:08.631233Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T17:46:08.63269Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-02T17:46:08.634961Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T17:46:08.635246Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T17:46:08.635369Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T17:46:08.635382Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T17:46:08.635869Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T17:46:08.637581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-04-02T17:46:08.637925Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-04-02T17:46:08.640394Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-02T17:46:08.640563Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-04-02T17:46:08.640647Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-04-02T17:46:08.641094Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T17:46:08.641147Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T17:46:08.72284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T17:46:08.722954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T17:46:08.723032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-04-02T17:46:08.723158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-04-02T17:46:08.723175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-04-02T17:46:08.723249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-04-02T17:46:08.723266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-04-02T17:46:08.725251Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T17:46:08.725474Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T17:46:08.725567Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T17:46:08.7256Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T17:46:08.725662Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T17:46:08.727634Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T17:46:08.727712Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T17:46:08.727757Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T17:46:08.729049Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T17:46:08.729327Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T17:46:08.732495Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-04-02T17:46:13.395967Z","caller":"traceutil/trace.go:171","msg":"trace[1850755868] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"191.373875ms","start":"2026-04-02T17:46:13.204567Z","end":"2026-04-02T17:46:13.395941Z","steps":["trace[1850755868] 'process raft request' (duration: 191.250131ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:46:15.118074Z","caller":"traceutil/trace.go:171","msg":"trace[685160600] transaction","detail":"{read_only:false; number_of_response:0; response_revision:311; }","duration":"111.668957ms","start":"2026-04-02T17:46:15.006387Z","end":"2026-04-02T17:46:15.118056Z","steps":["trace[685160600] 'process raft request' (duration: 111.590375ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:46:15.121917Z","caller":"traceutil/trace.go:171","msg":"trace[645534224] transaction","detail":"{read_only:false; number_of_response:0; response_revision:311; }","duration":"115.092241ms","start":"2026-04-02T17:46:15.006813Z","end":"2026-04-02T17:46:15.121905Z","steps":["trace[645534224] 'process raft request' (duration: 114.906025ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:46:15.121962Z","caller":"traceutil/trace.go:171","msg":"trace[32022969] transaction","detail":"{read_only:false; number_of_response:0; response_revision:311; }","duration":"114.942656ms","start":"2026-04-02T17:46:15.006992Z","end":"2026-04-02T17:46:15.121935Z","steps":["trace[32022969] 'process raft request' (duration: 114.836153ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:46:15.122029Z","caller":"traceutil/trace.go:171","msg":"trace[1273506769] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:320; }","duration":"102.143688ms","start":"2026-04-02T17:46:15.019851Z","end":"2026-04-02T17:46:15.121995Z","steps":["trace[1273506769] 'read index received' (duration: 98.051797ms)","trace[1273506769] 'applied index is now lower than readState.Index' (duration: 4.090521ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:46:15.122072Z","caller":"traceutil/trace.go:171","msg":"trace[1393962899] transaction","detail":"{read_only:false; number_of_response:0; response_revision:311; }","duration":"115.253165ms","start":"2026-04-02T17:46:15.006773Z","end":"2026-04-02T17:46:15.122026Z","steps":["trace[1393962899] 'process raft request' (duration: 115.08569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:46:15.122302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.386464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-02T17:46:15.12238Z","caller":"traceutil/trace.go:171","msg":"trace[1403801940] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:312; }","duration":"102.553629ms","start":"2026-04-02T17:46:15.019804Z","end":"2026-04-02T17:46:15.122358Z","steps":["trace[1403801940] 'agreement among raft nodes before linearized reading' (duration: 102.354773ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:46:18.440687Z","caller":"traceutil/trace.go:171","msg":"trace[2122690430] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"111.906934ms","start":"2026-04-02T17:46:18.328743Z","end":"2026-04-02T17:46:18.44065Z","steps":["trace[2122690430] 'process raft request' (duration: 111.668228ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:46:20.872036Z","caller":"traceutil/trace.go:171","msg":"trace[171671867] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"113.656712ms","start":"2026-04-02T17:46:20.758351Z","end":"2026-04-02T17:46:20.872008Z","steps":["trace[171671867] 'process raft request' (duration: 113.519338ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:46:21.227786Z","caller":"traceutil/trace.go:171","msg":"trace[233046088] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"187.341886ms","start":"2026-04-02T17:46:21.0404Z","end":"2026-04-02T17:46:21.227741Z","steps":["trace[233046088] 'read index received' (duration: 164.273488ms)","trace[233046088] 'applied index is now lower than readState.Index' (duration: 23.066778ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:46:21.227976Z","caller":"traceutil/trace.go:171","msg":"trace[1646167765] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"218.490622ms","start":"2026-04-02T17:46:21.009446Z","end":"2026-04-02T17:46:21.227937Z","steps":["trace[1646167765] 'process raft request' (duration: 195.267561ms)","trace[1646167765] 'compare' (duration: 22.839371ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:46:21.228129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.692025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-04-02T17:46:21.228189Z","caller":"traceutil/trace.go:171","msg":"trace[236215641] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:398; }","duration":"187.820598ms","start":"2026-04-02T17:46:21.040352Z","end":"2026-04-02T17:46:21.228172Z","steps":["trace[236215641] 'agreement among raft nodes before linearized reading' (duration: 187.672494ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:46:45.129494Z","caller":"traceutil/trace.go:171","msg":"trace[178598297] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"108.224704ms","start":"2026-04-02T17:46:45.02125Z","end":"2026-04-02T17:46:45.129475Z","steps":["trace[178598297] 'read index received' (duration: 107.393992ms)","trace[178598297] 'applied index is now lower than readState.Index' (duration: 830.072µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:46:45.129663Z","caller":"traceutil/trace.go:171","msg":"trace[1359235275] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"216.359574ms","start":"2026-04-02T17:46:44.913279Z","end":"2026-04-02T17:46:45.129639Z","steps":["trace[1359235275] 'process raft request' (duration: 215.648714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:46:45.129729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.46859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-02T17:46:45.129757Z","caller":"traceutil/trace.go:171","msg":"trace[1786660958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"108.542952ms","start":"2026-04-02T17:46:45.021206Z","end":"2026-04-02T17:46:45.129749Z","steps":["trace[1786660958] 'agreement among raft nodes before linearized reading' (duration: 108.420889ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:46:45.380721Z","caller":"traceutil/trace.go:171","msg":"trace[27202743] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"234.619842ms","start":"2026-04-02T17:46:45.146061Z","end":"2026-04-02T17:46:45.380681Z","steps":["trace[27202743] 'process raft request' (duration: 234.411535ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:46:45.4048Z","caller":"traceutil/trace.go:171","msg":"trace[356680726] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"216.851538ms","start":"2026-04-02T17:46:45.187897Z","end":"2026-04-02T17:46:45.404748Z","steps":["trace[356680726] 'process raft request' (duration: 216.516409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:46:46.414116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113416600515354,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T17:46:46.61367Z","caller":"traceutil/trace.go:171","msg":"trace[1718742029] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"700.246344ms","start":"2026-04-02T17:46:45.913396Z","end":"2026-04-02T17:46:46.613643Z","steps":["trace[1718742029] 'read index received' (duration: 699.161595ms)","trace[1718742029] 'applied index is now lower than readState.Index' (duration: 1.083769ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:46:46.613812Z","caller":"traceutil/trace.go:171","msg":"trace[512939551] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"741.306671ms","start":"2026-04-02T17:46:45.872492Z","end":"2026-04-02T17:46:46.613799Z","steps":["trace[512939551] 'process raft request' (duration: 740.362746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:46:46.614041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.850635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:46:46.614128Z","caller":"traceutil/trace.go:171","msg":"trace[524959238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"663.965267ms","start":"2026-04-02T17:46:45.950139Z","end":"2026-04-02T17:46:46.614104Z","steps":["trace[524959238] 'agreement among raft nodes before linearized reading' (duration: 663.798473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:46:46.614195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.776589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T17:46:46.614281Z","caller":"traceutil/trace.go:171","msg":"trace[1189390065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"700.908851ms","start":"2026-04-02T17:46:45.913349Z","end":"2026-04-02T17:46:46.614258Z","steps":["trace[1189390065] 'agreement among raft nodes before linearized reading' (duration: 700.654445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:46:46.614174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:46:45.950127Z","time spent":"664.03675ms","remote":"127.0.0.1:42744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T17:46:46.614335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:46:45.913329Z","time spent":"700.996384ms","remote":"127.0.0.1:43026","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-04-02T17:46:46.614502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:46:45.872463Z","time spent":"741.390204ms","remote":"127.0.0.1:42822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:47:08.370293Z","caller":"traceutil/trace.go:171","msg":"trace[542443966] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"159.970773ms","start":"2026-04-02T17:47:08.210285Z","end":"2026-04-02T17:47:08.370256Z","steps":["trace[542443966] 'read index received' (duration: 159.622584ms)","trace[542443966] 'applied index is now lower than readState.Index' (duration: 346.489µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:47:08.370338Z","caller":"traceutil/trace.go:171","msg":"trace[785432232] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"357.656003ms","start":"2026-04-02T17:47:08.012654Z","end":"2026-04-02T17:47:08.37031Z","steps":["trace[785432232] 'process raft request' (duration: 357.340975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:47:08.370549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.24674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ndfhm\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-02T17:47:08.370613Z","caller":"traceutil/trace.go:171","msg":"trace[2060403297] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ndfhm; range_end:; response_count:1; response_revision:803; }","duration":"160.361843ms","start":"2026-04-02T17:47:08.210232Z","end":"2026-04-02T17:47:08.370593Z","steps":["trace[2060403297] 'agreement among raft nodes before linearized reading' (duration: 160.160457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:47:08.3707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:47:08.012629Z","time spent":"357.88487ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:47:56.800065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.534211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:47:56.80017Z","caller":"traceutil/trace.go:171","msg":"trace[754206938] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1456; }","duration":"122.700545ms","start":"2026-04-02T17:47:56.677448Z","end":"2026-04-02T17:47:56.800148Z","steps":["trace[754206938] 'count revisions from in-memory index tree' (duration: 122.418778ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:47:59.145625Z","caller":"traceutil/trace.go:171","msg":"trace[2019245923] linearizableReadLoop","detail":"{readStateIndex:1510; appliedIndex:1509; }","duration":"196.252193ms","start":"2026-04-02T17:47:58.949344Z","end":"2026-04-02T17:47:59.145596Z","steps":["trace[2019245923] 'read index received' (duration: 196.14149ms)","trace[2019245923] 'applied index is now lower than readState.Index' (duration: 108.933µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:47:59.145745Z","caller":"traceutil/trace.go:171","msg":"trace[890599271] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"327.738434ms","start":"2026-04-02T17:47:58.81796Z","end":"2026-04-02T17:47:59.145698Z","steps":["trace[890599271] 'process raft request' (duration: 327.441757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:47:59.145808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.449607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:47:59.145878Z","caller":"traceutil/trace.go:171","msg":"trace[1298885326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1465; }","duration":"196.601031ms","start":"2026-04-02T17:47:58.949259Z","end":"2026-04-02T17:47:59.14586Z","steps":["trace[1298885326] 'agreement among raft nodes before linearized reading' (duration: 196.468998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:47:59.145906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:47:58.817934Z","time spent":"327.894877ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:47:59.2666Z","caller":"traceutil/trace.go:171","msg":"trace[982993064] transaction","detail":"{read_only:false; response_revision:1466; number_of_response:1; }","duration":"115.797497ms","start":"2026-04-02T17:47:59.15077Z","end":"2026-04-02T17:47:59.266568Z","steps":["trace[982993064] 'process raft request' (duration: 81.835651ms)","trace[982993064] 'compare' (duration: 33.809642ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:48:23.79176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.067674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T17:48:23.791863Z","caller":"traceutil/trace.go:171","msg":"trace[461420667] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1873; }","duration":"128.227978ms","start":"2026-04-02T17:48:23.663614Z","end":"2026-04-02T17:48:23.791841Z","steps":["trace[461420667] 'range keys from in-memory index tree' (duration: 127.850978ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:24.086509Z","caller":"traceutil/trace.go:171","msg":"trace[286883613] linearizableReadLoop","detail":"{readStateIndex:1946; appliedIndex:1945; }","duration":"332.703262ms","start":"2026-04-02T17:48:23.75378Z","end":"2026-04-02T17:48:24.086483Z","steps":["trace[286883613] 'read index received' (duration: 332.562679ms)","trace[286883613] 'applied index is now lower than readState.Index' (duration: 139.223µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:24.086632Z","caller":"traceutil/trace.go:171","msg":"trace[1745160880] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"340.484142ms","start":"2026-04-02T17:48:23.746137Z","end":"2026-04-02T17:48:24.086622Z","steps":["trace[1745160880] 'process raft request' (duration: 340.174774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:24.086739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:48:23.746107Z","time spent":"340.550634ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:48:24.086873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.077832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-348ce9c1-5cb3-4a86-9642-5d070aca5787\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-02T17:48:24.086954Z","caller":"traceutil/trace.go:171","msg":"trace[1415480605] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-348ce9c1-5cb3-4a86-9642-5d070aca5787; range_end:; response_count:1; response_revision:1874; }","duration":"333.204195ms","start":"2026-04-02T17:48:23.753732Z","end":"2026-04-02T17:48:24.086937Z","steps":["trace[1415480605] 'agreement among raft nodes before linearized reading' (duration: 333.00122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:24.087002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:48:23.753717Z","time spent":"333.275757ms","remote":"127.0.0.1:42920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-348ce9c1-5cb3-4a86-9642-5d070aca5787\" "} {"level":"info","ts":"2026-04-02T17:48:24.344724Z","caller":"traceutil/trace.go:171","msg":"trace[768145662] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"548.264422ms","start":"2026-04-02T17:48:23.796426Z","end":"2026-04-02T17:48:24.344691Z","steps":["trace[768145662] 'process raft request' (duration: 472.828566ms)","trace[768145662] 'compare' (duration: 75.243751ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:48:24.344886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:48:23.796399Z","time spent":"548.396965ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:48:24.345244Z","caller":"traceutil/trace.go:171","msg":"trace[244246742] linearizableReadLoop","detail":"{readStateIndex:1949; appliedIndex:1946; }","duration":"258.644752ms","start":"2026-04-02T17:48:24.086579Z","end":"2026-04-02T17:48:24.345223Z","steps":["trace[244246742] 'read index received' (duration: 182.61377ms)","trace[244246742] 'applied index is now lower than readState.Index' (duration: 76.029772ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:24.345397Z","caller":"traceutil/trace.go:171","msg":"trace[1896691582] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"347.908575ms","start":"2026-04-02T17:48:23.997478Z","end":"2026-04-02T17:48:24.345387Z","steps":["trace[1896691582] 'process raft request' (duration: 347.649049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:24.345455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:48:23.997464Z","time spent":"347.964846ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:48:24.345445Z","caller":"traceutil/trace.go:171","msg":"trace[1108272718] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"358.885908ms","start":"2026-04-02T17:48:23.986515Z","end":"2026-04-02T17:48:24.345401Z","steps":["trace[1108272718] 'process raft request' (duration: 358.139778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:24.345526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.438381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:48:24.345579Z","caller":"traceutil/trace.go:171","msg":"trace[322855858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1877; }","duration":"395.522743ms","start":"2026-04-02T17:48:23.950042Z","end":"2026-04-02T17:48:24.345565Z","steps":["trace[322855858] 'agreement among raft nodes before linearized reading' (duration: 395.43066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:24.345626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.734079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-02T17:48:24.345633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:48:23.950032Z","time spent":"395.588325ms","remote":"127.0.0.1:42744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T17:48:24.345652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:48:23.986485Z","time spent":"359.061822ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:48:24.34572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.339757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-02T17:48:24.345756Z","caller":"traceutil/trace.go:171","msg":"trace[1322659212] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1877; }","duration":"414.398829ms","start":"2026-04-02T17:48:23.931349Z","end":"2026-04-02T17:48:24.345748Z","steps":["trace[1322659212] 'agreement among raft nodes before linearized reading' (duration: 414.282176ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:24.345674Z","caller":"traceutil/trace.go:171","msg":"trace[1741858799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1877; }","duration":"421.835762ms","start":"2026-04-02T17:48:23.923827Z","end":"2026-04-02T17:48:24.345663Z","steps":["trace[1741858799] 'agreement among raft nodes before linearized reading' (duration: 421.481393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:24.345787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:48:23.931335Z","time spent":"414.443ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T17:48:24.345801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:48:23.923803Z","time spent":"421.989566ms","remote":"127.0.0.1:43026","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-04-02T17:48:24.736176Z","caller":"traceutil/trace.go:171","msg":"trace[1073117573] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"384.821516ms","start":"2026-04-02T17:48:24.35133Z","end":"2026-04-02T17:48:24.736152Z","steps":["trace[1073117573] 'process raft request' (duration: 384.676813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:24.736373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:48:24.351318Z","time spent":"384.95919ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:48:24.745826Z","caller":"traceutil/trace.go:171","msg":"trace[577742579] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1949; }","duration":"221.575286ms","start":"2026-04-02T17:48:24.524232Z","end":"2026-04-02T17:48:24.745807Z","steps":["trace[577742579] 'read index received' (duration: 211.793963ms)","trace[577742579] 'applied index is now lower than readState.Index' (duration: 9.780443ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:24.745883Z","caller":"traceutil/trace.go:171","msg":"trace[714244957] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"392.969277ms","start":"2026-04-02T17:48:24.352889Z","end":"2026-04-02T17:48:24.745858Z","steps":["trace[714244957] 'process raft request' (duration: 392.821504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:24.745969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.057077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T17:48:24.746017Z","caller":"traceutil/trace.go:171","msg":"trace[1987766800] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1879; }","duration":"170.155589ms","start":"2026-04-02T17:48:24.575846Z","end":"2026-04-02T17:48:24.746002Z","steps":["trace[1987766800] 'agreement among raft nodes before linearized reading' (duration: 170.076847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:24.746027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:48:24.352866Z","time spent":"393.0739ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:48:24.74607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.827073ms","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-04-02T17:48:24.746071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.893372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-348ce9c1-5cb3-4a86-9642-5d070aca5787\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-02T17:48:24.746149Z","caller":"traceutil/trace.go:171","msg":"trace[1891127285] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1879; }","duration":"221.961456ms","start":"2026-04-02T17:48:24.524164Z","end":"2026-04-02T17:48:24.746125Z","steps":["trace[1891127285] 'agreement among raft nodes before linearized reading' (duration: 221.70709ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:24.746191Z","caller":"traceutil/trace.go:171","msg":"trace[871639417] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-348ce9c1-5cb3-4a86-9642-5d070aca5787; range_end:; response_count:1; response_revision:1879; }","duration":"132.040296ms","start":"2026-04-02T17:48:24.614134Z","end":"2026-04-02T17:48:24.746174Z","steps":["trace[871639417] 'agreement among raft nodes before linearized reading' (duration: 131.84084ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:37.708341Z","caller":"traceutil/trace.go:171","msg":"trace[41266667] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"382.260541ms","start":"2026-04-02T17:48:37.326047Z","end":"2026-04-02T17:48:37.708308Z","steps":["trace[41266667] 'process raft request' (duration: 382.012345ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:37.708575Z","caller":"traceutil/trace.go:171","msg":"trace[2105800881] linearizableReadLoop","detail":"{readStateIndex:2059; appliedIndex:2056; }","duration":"262.331727ms","start":"2026-04-02T17:48:37.446203Z","end":"2026-04-02T17:48:37.708535Z","steps":["trace[2105800881] 'read index received' (duration: 211.632319ms)","trace[2105800881] 'applied index is now lower than readState.Index' (duration: 50.693918ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:37.708651Z","caller":"traceutil/trace.go:171","msg":"trace[1184444825] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"366.532185ms","start":"2026-04-02T17:48:37.342043Z","end":"2026-04-02T17:48:37.708576Z","steps":["trace[1184444825] 'process raft request' (duration: 366.193046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:37.708793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:48:37.342023Z","time spent":"366.72195ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:48:37.708833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.625395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T17:48:37.708874Z","caller":"traceutil/trace.go:171","msg":"trace[1410536143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1984; }","duration":"262.685976ms","start":"2026-04-02T17:48:37.446176Z","end":"2026-04-02T17:48:37.708862Z","steps":["trace[1410536143] 'agreement among raft nodes before linearized reading' (duration: 262.535072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:48:37.70866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:48:37.326022Z","time spent":"382.530008ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:48:37.723284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.413289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11978"} {"level":"info","ts":"2026-04-02T17:48:37.723407Z","caller":"traceutil/trace.go:171","msg":"trace[514197413] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1985; }","duration":"193.614604ms","start":"2026-04-02T17:48:37.529766Z","end":"2026-04-02T17:48:37.72338Z","steps":["trace[514197413] 'agreement among raft nodes before linearized reading' (duration: 193.255975ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:42.024195Z","caller":"traceutil/trace.go:171","msg":"trace[314475119] linearizableReadLoop","detail":"{readStateIndex:2091; appliedIndex:2089; }","duration":"285.252967ms","start":"2026-04-02T17:48:41.738902Z","end":"2026-04-02T17:48:42.024155Z","steps":["trace[314475119] 'read index received' (duration: 466.032µs)","trace[314475119] 'applied index is now lower than readState.Index' (duration: 284.785565ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:48:42.024337Z","caller":"traceutil/trace.go:171","msg":"trace[229561188] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"290.39382ms","start":"2026-04-02T17:48:41.733928Z","end":"2026-04-02T17:48:42.024321Z","steps":["trace[229561188] 'process raft request' (duration: 196.997102ms)","trace[229561188] 'compare' (duration: 93.024509ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:48:42.024625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.531002ms","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-04-02T17:48:42.024651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.7361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T17:48:42.024721Z","caller":"traceutil/trace.go:171","msg":"trace[2126675244] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2016; }","duration":"145.665007ms","start":"2026-04-02T17:48:41.879033Z","end":"2026-04-02T17:48:42.024698Z","steps":["trace[2126675244] 'agreement among raft nodes before linearized reading' (duration: 145.379318ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:48:42.024723Z","caller":"traceutil/trace.go:171","msg":"trace[837669473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2016; }","duration":"285.860884ms","start":"2026-04-02T17:48:41.738846Z","end":"2026-04-02T17:48:42.024706Z","steps":["trace[837669473] 'agreement among raft nodes before linearized reading' (duration: 285.604546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:14.38341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.172086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T17:49:14.383629Z","caller":"traceutil/trace.go:171","msg":"trace[916898535] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2195; }","duration":"123.437243ms","start":"2026-04-02T17:49:14.260152Z","end":"2026-04-02T17:49:14.38359Z","steps":["trace[916898535] 'count revisions from in-memory index tree' (duration: 123.086664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:14.383741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.284659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-02T17:49:14.383892Z","caller":"traceutil/trace.go:171","msg":"trace[1138669603] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2195; }","duration":"116.505964ms","start":"2026-04-02T17:49:14.267351Z","end":"2026-04-02T17:49:14.383857Z","steps":["trace[1138669603] 'range keys from in-memory index tree' (duration: 116.005682ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:20.391063Z","caller":"traceutil/trace.go:171","msg":"trace[111485740] linearizableReadLoop","detail":"{readStateIndex:2311; appliedIndex:2310; }","duration":"180.347056ms","start":"2026-04-02T17:49:20.210676Z","end":"2026-04-02T17:49:20.391023Z","steps":["trace[111485740] 'read index received' (duration: 173.321565ms)","trace[111485740] 'applied index is now lower than readState.Index' (duration: 7.023741ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:49:20.391142Z","caller":"traceutil/trace.go:171","msg":"trace[613852824] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"217.247224ms","start":"2026-04-02T17:49:20.173853Z","end":"2026-04-02T17:49:20.3911Z","steps":["trace[613852824] 'process raft request' (duration: 210.319546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:20.391437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.749096ms","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-04-02T17:49:20.391513Z","caller":"traceutil/trace.go:171","msg":"trace[1094973301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2227; }","duration":"180.899819ms","start":"2026-04-02T17:49:20.210594Z","end":"2026-04-02T17:49:20.391493Z","steps":["trace[1094973301] 'agreement among raft nodes before linearized reading' (duration: 180.560061ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:20.783607Z","caller":"traceutil/trace.go:171","msg":"trace[1428023024] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"387.458884ms","start":"2026-04-02T17:49:20.396127Z","end":"2026-04-02T17:49:20.783586Z","steps":["trace[1428023024] 'process raft request' (duration: 337.829586ms)","trace[1428023024] 'compare' (duration: 49.32333ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:49:20.783737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:49:20.39609Z","time spent":"387.572166ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:49:20.783776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.185966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-02T17:49:20.783829Z","caller":"traceutil/trace.go:171","msg":"trace[1349108965] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2228; }","duration":"352.283888ms","start":"2026-04-02T17:49:20.431531Z","end":"2026-04-02T17:49:20.783815Z","steps":["trace[1349108965] 'agreement among raft nodes before linearized reading' (duration: 352.111904ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:20.783591Z","caller":"traceutil/trace.go:171","msg":"trace[1459562267] linearizableReadLoop","detail":"{readStateIndex:2312; appliedIndex:2311; }","duration":"351.97914ms","start":"2026-04-02T17:49:20.431586Z","end":"2026-04-02T17:49:20.783565Z","steps":["trace[1459562267] 'read index received' (duration: 302.398704ms)","trace[1459562267] 'applied index is now lower than readState.Index' (duration: 49.579206ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:49:20.784029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:49:20.43151Z","time spent":"352.343729ms","remote":"127.0.0.1:54790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-02T17:49:20.78541Z","caller":"traceutil/trace.go:171","msg":"trace[217170000] transaction","detail":"{read_only:false; response_revision:2229; number_of_response:1; }","duration":"154.737523ms","start":"2026-04-02T17:49:20.630623Z","end":"2026-04-02T17:49:20.78536Z","steps":["trace[217170000] 'process raft request' (duration: 154.335413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:20.785515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.696187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T17:49:20.785673Z","caller":"traceutil/trace.go:171","msg":"trace[2058822681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2230; }","duration":"141.951863ms","start":"2026-04-02T17:49:20.643688Z","end":"2026-04-02T17:49:20.78564Z","steps":["trace[2058822681] 'agreement among raft nodes before linearized reading' (duration: 141.570474ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:49:20.786208Z","caller":"traceutil/trace.go:171","msg":"trace[1673616986] transaction","detail":"{read_only:false; response_revision:2230; number_of_response:1; }","duration":"153.497623ms","start":"2026-04-02T17:49:20.63198Z","end":"2026-04-02T17:49:20.785478Z","steps":["trace[1673616986] 'process raft request' (duration: 153.164005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:21.19914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.2116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:49:21.199318Z","caller":"traceutil/trace.go:171","msg":"trace[2000122198] linearizableReadLoop","detail":"{readStateIndex:2315; appliedIndex:2314; }","duration":"377.930322ms","start":"2026-04-02T17:49:20.821362Z","end":"2026-04-02T17:49:21.199292Z","steps":["trace[2000122198] 'read index received' (duration: 200.143998ms)","trace[2000122198] 'applied index is now lower than readState.Index' (duration: 177.783434ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:49:21.199443Z","caller":"traceutil/trace.go:171","msg":"trace[762796266] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"409.332857ms","start":"2026-04-02T17:49:20.790044Z","end":"2026-04-02T17:49:21.199377Z","steps":["trace[762796266] 'process raft request' (duration: 231.545683ms)","trace[762796266] 'compare' (duration: 177.035605ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:49:21.199726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.348462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T17:49:21.199944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.670412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-02T17:49:21.199984Z","caller":"traceutil/trace.go:171","msg":"trace[1111392859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2231; }","duration":"184.767944ms","start":"2026-04-02T17:49:21.015204Z","end":"2026-04-02T17:49:21.199972Z","steps":["trace[1111392859] 'agreement among raft nodes before linearized reading' (duration: 184.635251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:21.199989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:49:20.790019Z","time spent":"409.784388ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:49:21.200019Z","caller":"traceutil/trace.go:171","msg":"trace[32180506] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2231; }","duration":"378.711391ms","start":"2026-04-02T17:49:20.821262Z","end":"2026-04-02T17:49:21.199974Z","steps":["trace[32180506] 'agreement among raft nodes before linearized reading' (duration: 378.386843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:49:21.200101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.527952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-02T17:49:21.200165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:49:20.821239Z","time spent":"378.869205ms","remote":"127.0.0.1:56902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-04-02T17:49:21.200179Z","caller":"traceutil/trace.go:171","msg":"trace[1671604851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2231; }","duration":"185.684636ms","start":"2026-04-02T17:49:21.01448Z","end":"2026-04-02T17:49:21.200165Z","steps":["trace[1671604851] 'agreement among raft nodes before linearized reading' (duration: 185.369679ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:50:00.959323Z","caller":"traceutil/trace.go:171","msg":"trace[252449605] transaction","detail":"{read_only:false; response_revision:2613; number_of_response:1; }","duration":"163.100403ms","start":"2026-04-02T17:50:00.796191Z","end":"2026-04-02T17:50:00.959292Z","steps":["trace[252449605] 'process raft request' (duration: 162.935328ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:50:04.68978Z","caller":"traceutil/trace.go:171","msg":"trace[1726276708] linearizableReadLoop","detail":"{readStateIndex:2738; appliedIndex:2737; }","duration":"313.714598ms","start":"2026-04-02T17:50:04.376023Z","end":"2026-04-02T17:50:04.689738Z","steps":["trace[1726276708] 'read index received' (duration: 313.660486ms)","trace[1726276708] 'applied index is now lower than readState.Index' (duration: 53.292µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:50:04.689877Z","caller":"traceutil/trace.go:171","msg":"trace[1056431922] transaction","detail":"{read_only:false; response_revision:2638; number_of_response:1; }","duration":"432.83699ms","start":"2026-04-02T17:50:04.257016Z","end":"2026-04-02T17:50:04.689853Z","steps":["trace[1056431922] 'process raft request' (duration: 432.577543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:04.689928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.890672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T17:50:04.689962Z","caller":"traceutil/trace.go:171","msg":"trace[100009196] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2638; }","duration":"313.980964ms","start":"2026-04-02T17:50:04.375971Z","end":"2026-04-02T17:50:04.689952Z","steps":["trace[100009196] 'agreement among raft nodes before linearized reading' (duration: 313.906192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:04.689997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:04.375949Z","time spent":"314.038485ms","remote":"127.0.0.1:42912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-04-02T17:50:04.690011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:04.257006Z","time spent":"432.938302ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:50:04.691008Z","caller":"traceutil/trace.go:171","msg":"trace[524861094] transaction","detail":"{read_only:false; response_revision:2639; number_of_response:1; }","duration":"137.597753ms","start":"2026-04-02T17:50:04.553392Z","end":"2026-04-02T17:50:04.69099Z","steps":["trace[524861094] 'process raft request' (duration: 137.439799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:04.691076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.585534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-02T17:50:04.691149Z","caller":"traceutil/trace.go:171","msg":"trace[1943148072] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2639; }","duration":"302.686847ms","start":"2026-04-02T17:50:04.388442Z","end":"2026-04-02T17:50:04.691129Z","steps":["trace[1943148072] 'agreement among raft nodes before linearized reading' (duration: 302.488412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:04.691192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:04.388431Z","time spent":"302.751498ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-04-02T17:50:13.597282Z","caller":"traceutil/trace.go:171","msg":"trace[1364858109] transaction","detail":"{read_only:false; response_revision:2698; number_of_response:1; }","duration":"123.776201ms","start":"2026-04-02T17:50:13.473395Z","end":"2026-04-02T17:50:13.597171Z","steps":["trace[1364858109] 'process raft request' (duration: 123.524075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:32.961344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.959335ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:50:32.96145Z","caller":"traceutil/trace.go:171","msg":"trace[412979804] linearizableReadLoop","detail":"{readStateIndex:2919; appliedIndex:2918; }","duration":"572.372142ms","start":"2026-04-02T17:50:32.389056Z","end":"2026-04-02T17:50:32.961428Z","steps":["trace[412979804] 'read index received' (duration: 102.061808ms)","trace[412979804] 'applied index is now lower than readState.Index' (duration: 470.308924ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:50:32.961562Z","caller":"traceutil/trace.go:171","msg":"trace[627044633] transaction","detail":"{read_only:false; response_revision:2812; number_of_response:1; }","duration":"662.166388ms","start":"2026-04-02T17:50:32.299352Z","end":"2026-04-02T17:50:32.961518Z","steps":["trace[627044633] 'process raft request' (duration: 191.866664ms)","trace[627044633] 'compare' (duration: 469.648818ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:50:32.961634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.587368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-02T17:50:32.961668Z","caller":"traceutil/trace.go:171","msg":"trace[924900596] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2812; }","duration":"572.67661ms","start":"2026-04-02T17:50:32.388981Z","end":"2026-04-02T17:50:32.961658Z","steps":["trace[924900596] 'agreement among raft nodes before linearized reading' (duration: 572.524286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:32.961693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:32.388957Z","time spent":"572.729781ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-04-02T17:50:32.962208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:32.299316Z","time spent":"662.344893ms","remote":"127.0.0.1:42768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:50:33.295982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.923583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:50:33.29613Z","caller":"traceutil/trace.go:171","msg":"trace[1291784203] transaction","detail":"{read_only:false; response_revision:2813; number_of_response:1; }","duration":"477.351494ms","start":"2026-04-02T17:50:32.818755Z","end":"2026-04-02T17:50:33.296107Z","steps":["trace[1291784203] 'process raft request' (duration: 282.182134ms)","trace[1291784203] 'compare' (duration: 194.733809ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:50:33.296426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:32.818721Z","time spent":"477.667342ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:50:33.32223Z","caller":"traceutil/trace.go:171","msg":"trace[1227847503] transaction","detail":"{read_only:false; response_revision:2814; number_of_response:1; }","duration":"503.266974ms","start":"2026-04-02T17:50:32.818914Z","end":"2026-04-02T17:50:33.322181Z","steps":["trace[1227847503] 'process raft request' (duration: 503.097269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:33.32234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:32.818839Z","time spent":"503.445628ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:50:33.322451Z","caller":"traceutil/trace.go:171","msg":"trace[1077889620] transaction","detail":"{read_only:false; response_revision:2815; number_of_response:1; }","duration":"234.518631ms","start":"2026-04-02T17:50:33.087896Z","end":"2026-04-02T17:50:33.322415Z","steps":["trace[1077889620] 'process raft request' (duration: 234.203924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:33.323423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.441686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-02T17:50:33.323553Z","caller":"traceutil/trace.go:171","msg":"trace[631110476] linearizableReadLoop","detail":"{readStateIndex:2921; appliedIndex:2919; }","duration":"360.9262ms","start":"2026-04-02T17:50:32.961476Z","end":"2026-04-02T17:50:33.322402Z","steps":["trace[631110476] 'read index received' (duration: 139.481252ms)","trace[631110476] 'applied index is now lower than readState.Index' (duration: 221.442218ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:50:33.323577Z","caller":"traceutil/trace.go:171","msg":"trace[369414912] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2815; }","duration":"457.619742ms","start":"2026-04-02T17:50:32.865904Z","end":"2026-04-02T17:50:33.323523Z","steps":["trace[369414912] 'agreement among raft nodes before linearized reading' (duration: 456.531694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:33.323639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:32.865891Z","time spent":"457.734955ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-02T17:50:33.332981Z","caller":"traceutil/trace.go:171","msg":"trace[1296962240] transaction","detail":"{read_only:false; response_revision:2816; number_of_response:1; }","duration":"175.641712ms","start":"2026-04-02T17:50:33.157293Z","end":"2026-04-02T17:50:33.332937Z","steps":["trace[1296962240] 'process raft request' (duration: 175.403236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:33.332996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.475502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-02T17:50:33.333207Z","caller":"traceutil/trace.go:171","msg":"trace[1715068967] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2816; }","duration":"368.76913ms","start":"2026-04-02T17:50:32.964428Z","end":"2026-04-02T17:50:33.333197Z","steps":["trace[1715068967] 'agreement among raft nodes before linearized reading' (duration: 368.445152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:33.333302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:32.96441Z","time spent":"368.880142ms","remote":"127.0.0.1:42906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-04-02T17:50:33.333372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.670161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-04-02T17:50:33.333458Z","caller":"traceutil/trace.go:171","msg":"trace[52047001] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2816; }","duration":"367.805774ms","start":"2026-04-02T17:50:32.965632Z","end":"2026-04-02T17:50:33.333438Z","steps":["trace[52047001] 'agreement among raft nodes before linearized reading' (duration: 367.566498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:33.33351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:32.965613Z","time spent":"367.887677ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1470,"request content":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" "} {"level":"info","ts":"2026-04-02T17:50:39.761973Z","caller":"traceutil/trace.go:171","msg":"trace[1150881273] transaction","detail":"{read_only:false; response_revision:2844; number_of_response:1; }","duration":"590.141576ms","start":"2026-04-02T17:50:39.171804Z","end":"2026-04-02T17:50:39.761945Z","steps":["trace[1150881273] 'process raft request' (duration: 589.931071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:39.762269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:39.171772Z","time spent":"590.28555ms","remote":"127.0.0.1:42810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:50:40.38192Z","caller":"traceutil/trace.go:171","msg":"trace[491781224] transaction","detail":"{read_only:false; response_revision:2845; number_of_response:1; }","duration":"1.032856857s","start":"2026-04-02T17:50:39.349025Z","end":"2026-04-02T17:50:40.381882Z","steps":["trace[491781224] 'process raft request' (duration: 953.585309ms)","trace[491781224] 'compare' (duration: 78.571811ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:50:40.382206Z","caller":"traceutil/trace.go:171","msg":"trace[37316203] transaction","detail":"{read_only:false; response_revision:2846; number_of_response:1; }","duration":"1.032321343s","start":"2026-04-02T17:50:39.349855Z","end":"2026-04-02T17:50:40.382176Z","steps":["trace[37316203] 'process raft request' (duration: 1.031915642s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:40.382367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:39.348987Z","time spent":"1.033017171s","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:50:40.382573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:39.34983Z","time spent":"1.032448756s","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:50:40.382635Z","caller":"traceutil/trace.go:171","msg":"trace[10258572] transaction","detail":"{read_only:false; response_revision:2847; number_of_response:1; }","duration":"1.030774004s","start":"2026-04-02T17:50:39.351786Z","end":"2026-04-02T17:50:40.38256Z","steps":["trace[10258572] 'process raft request' (duration: 1.030294532s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:40.382869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:39.351759Z","time spent":"1.030992919s","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:50:40.382981Z","caller":"traceutil/trace.go:171","msg":"trace[786550817] linearizableReadLoop","detail":"{readStateIndex:2955; appliedIndex:2951; }","duration":"1.013926885s","start":"2026-04-02T17:50:39.368994Z","end":"2026-04-02T17:50:40.382921Z","steps":["trace[786550817] 'read index received' (duration: 392.912554ms)","trace[786550817] 'applied index is now lower than readState.Index' (duration: 621.010971ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:50:40.383105Z","caller":"traceutil/trace.go:171","msg":"trace[1856894088] transaction","detail":"{read_only:false; response_revision:2848; number_of_response:1; }","duration":"691.222099ms","start":"2026-04-02T17:50:39.691767Z","end":"2026-04-02T17:50:40.382989Z","steps":["trace[1856894088] 'process raft request' (duration: 690.668204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:40.383189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.691052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T17:50:40.383212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004939517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T17:50:40.38327Z","caller":"traceutil/trace.go:171","msg":"trace[409690425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2848; }","duration":"497.769474ms","start":"2026-04-02T17:50:39.88548Z","end":"2026-04-02T17:50:40.38325Z","steps":["trace[409690425] 'agreement among raft nodes before linearized reading' (duration: 497.647671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:40.383287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:39.69174Z","time spent":"691.447565ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:50:40.383296Z","caller":"traceutil/trace.go:171","msg":"trace[718587216] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2848; }","duration":"1.00507118s","start":"2026-04-02T17:50:39.378204Z","end":"2026-04-02T17:50:40.383275Z","steps":["trace[718587216] 'agreement among raft nodes before linearized reading' (duration: 1.004926467s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:40.38333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:39.885399Z","time spent":"497.921327ms","remote":"127.0.0.1:42744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T17:50:40.383351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:39.378183Z","time spent":"1.005156182s","remote":"127.0.0.1:32834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-02T17:50:40.383496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.472963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-02T17:50:40.383606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.850568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-02T17:50:40.383675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.192782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-02T17:50:40.383722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.919304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-02T17:50:40.383705Z","caller":"traceutil/trace.go:171","msg":"trace[980211187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2848; }","duration":"656.027173ms","start":"2026-04-02T17:50:39.727662Z","end":"2026-04-02T17:50:40.383689Z","steps":["trace[980211187] 'agreement among raft nodes before linearized reading' (duration: 655.781046ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:50:40.383774Z","caller":"traceutil/trace.go:171","msg":"trace[2035717925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2848; }","duration":"981.013027ms","start":"2026-04-02T17:50:39.40275Z","end":"2026-04-02T17:50:40.383763Z","steps":["trace[2035717925] 'agreement among raft nodes before linearized reading' (duration: 980.832752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:40.38373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.492112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T17:50:40.383632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014537811s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-02T17:50:40.383763Z","caller":"traceutil/trace.go:171","msg":"trace[2127461680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2848; }","duration":"655.331885ms","start":"2026-04-02T17:50:39.728417Z","end":"2026-04-02T17:50:40.383749Z","steps":["trace[2127461680] 'agreement among raft nodes before linearized reading' (duration: 654.71864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:40.384003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:39.728397Z","time spent":"655.593012ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T17:50:40.384042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.143804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T17:50:40.383812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:39.402727Z","time spent":"981.077709ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T17:50:40.383958Z","caller":"traceutil/trace.go:171","msg":"trace[426556350] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2848; }","duration":"1.014971512s","start":"2026-04-02T17:50:39.368953Z","end":"2026-04-02T17:50:40.383925Z","steps":["trace[426556350] 'agreement among raft nodes before linearized reading' (duration: 1.014159551s)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:50:40.383874Z","caller":"traceutil/trace.go:171","msg":"trace[464780813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2848; }","duration":"434.676757ms","start":"2026-04-02T17:50:39.949186Z","end":"2026-04-02T17:50:40.383862Z","steps":["trace[464780813] 'agreement among raft nodes before linearized reading' (duration: 434.460822ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:50:40.384277Z","caller":"traceutil/trace.go:171","msg":"trace[674927565] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2848; }","duration":"357.247396ms","start":"2026-04-02T17:50:40.026823Z","end":"2026-04-02T17:50:40.384071Z","steps":["trace[674927565] 'agreement among raft nodes before linearized reading' (duration: 357.156334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:40.384289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:39.949164Z","time spent":"435.113347ms","remote":"127.0.0.1:42748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T17:50:40.384412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:40.026804Z","time spent":"357.593614ms","remote":"127.0.0.1:44212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-04-02T17:50:40.383561Z","caller":"traceutil/trace.go:171","msg":"trace[582775016] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2848; }","duration":"654.557746ms","start":"2026-04-02T17:50:39.728986Z","end":"2026-04-02T17:50:40.383544Z","steps":["trace[582775016] 'agreement among raft nodes before linearized reading' (duration: 654.163476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:40.384851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:39.728975Z","time spent":"655.846848ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T17:50:40.384209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:39.368937Z","time spent":"1.015246088s","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T17:50:40.383821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:50:39.727636Z","time spent":"656.172117ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T17:50:40.528889Z","caller":"traceutil/trace.go:171","msg":"trace[315286331] linearizableReadLoop","detail":"{readStateIndex:2961; appliedIndex:2956; }","duration":"130.620376ms","start":"2026-04-02T17:50:40.398225Z","end":"2026-04-02T17:50:40.528846Z","steps":["trace[315286331] 'read index received' (duration: 66.426531ms)","trace[315286331] 'applied index is now lower than readState.Index' (duration: 64.191864ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:50:40.528968Z","caller":"traceutil/trace.go:171","msg":"trace[1871884906] transaction","detail":"{read_only:false; response_revision:2852; number_of_response:1; }","duration":"131.364014ms","start":"2026-04-02T17:50:40.397556Z","end":"2026-04-02T17:50:40.52892Z","steps":["trace[1871884906] 'process raft request' (duration: 131.19481ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:50:40.529132Z","caller":"traceutil/trace.go:171","msg":"trace[1184894377] transaction","detail":"{read_only:false; response_revision:2853; number_of_response:1; }","duration":"131.417326ms","start":"2026-04-02T17:50:40.397691Z","end":"2026-04-02T17:50:40.529108Z","steps":["trace[1184894377] 'process raft request' (duration: 131.102238ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:50:40.529199Z","caller":"traceutil/trace.go:171","msg":"trace[1384044120] transaction","detail":"{read_only:false; response_revision:2851; number_of_response:1; }","duration":"137.034429ms","start":"2026-04-02T17:50:40.392157Z","end":"2026-04-02T17:50:40.529191Z","steps":["trace[1384044120] 'process raft request' (duration: 136.529787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:50:40.529516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.253742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-04-02T17:50:40.52957Z","caller":"traceutil/trace.go:171","msg":"trace[1911558336] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2853; }","duration":"131.354794ms","start":"2026-04-02T17:50:40.398199Z","end":"2026-04-02T17:50:40.529553Z","steps":["trace[1911558336] 'agreement among raft nodes before linearized reading' (duration: 131.103338ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:50:40.529663Z","caller":"traceutil/trace.go:171","msg":"trace[773229915] transaction","detail":"{read_only:false; response_revision:2850; number_of_response:1; }","duration":"137.309626ms","start":"2026-04-02T17:50:40.392084Z","end":"2026-04-02T17:50:40.529394Z","steps":["trace[773229915] 'process raft request' (duration: 126.115481ms)","trace[773229915] 'compare' (duration: 10.21282ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:51:06.715692Z","caller":"traceutil/trace.go:171","msg":"trace[932163401] transaction","detail":"{read_only:false; response_revision:2993; number_of_response:1; }","duration":"112.903301ms","start":"2026-04-02T17:51:06.602741Z","end":"2026-04-02T17:51:06.715644Z","steps":["trace[932163401] 'process raft request' (duration: 37.113407ms)","trace[932163401] 'compare' (duration: 75.473686ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:54:41.327665Z","caller":"traceutil/trace.go:171","msg":"trace[1061841421] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"254.385308ms","start":"2026-04-02T17:54:41.073247Z","end":"2026-04-02T17:54:41.327632Z","steps":["trace[1061841421] 'process raft request' (duration: 254.182533ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:54:45.438062Z","caller":"traceutil/trace.go:171","msg":"trace[1391105334] linearizableReadLoop","detail":"{readStateIndex:5250; appliedIndex:5249; }","duration":"284.277679ms","start":"2026-04-02T17:54:45.15375Z","end":"2026-04-02T17:54:45.438028Z","steps":["trace[1391105334] 'read index received' (duration: 284.080854ms)","trace[1391105334] 'applied index is now lower than readState.Index' (duration: 195.304µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:54:45.438216Z","caller":"traceutil/trace.go:171","msg":"trace[325022229] transaction","detail":"{read_only:false; response_revision:5060; number_of_response:1; }","duration":"290.821538ms","start":"2026-04-02T17:54:45.147383Z","end":"2026-04-02T17:54:45.438204Z","steps":["trace[325022229] 'process raft request' (duration: 290.384637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:54:45.438549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.774311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/monitoring\" ","response":"range_response_count:1 size:372"} {"level":"info","ts":"2026-04-02T17:54:45.438597Z","caller":"traceutil/trace.go:171","msg":"trace[841290321] range","detail":"{range_begin:/registry/namespaces/monitoring; range_end:; response_count:1; response_revision:5060; }","duration":"284.875983ms","start":"2026-04-02T17:54:45.15371Z","end":"2026-04-02T17:54:45.438586Z","steps":["trace[841290321] 'agreement among raft nodes before linearized reading' (duration: 284.674588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:54:45.456695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.965692ms","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-04-02T17:54:45.456799Z","caller":"traceutil/trace.go:171","msg":"trace[781568181] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5061; }","duration":"112.121715ms","start":"2026-04-02T17:54:45.344651Z","end":"2026-04-02T17:54:45.456773Z","steps":["trace[781568181] 'agreement among raft nodes before linearized reading' (duration: 111.824578ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:54:45.456741Z","caller":"traceutil/trace.go:171","msg":"trace[1719960283] transaction","detail":"{read_only:false; response_revision:5061; number_of_response:1; }","duration":"276.164574ms","start":"2026-04-02T17:54:45.180525Z","end":"2026-04-02T17:54:45.45669Z","steps":["trace[1719960283] 'process raft request' (duration: 275.630892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:54:45.890595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.768468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:54:45.890839Z","caller":"traceutil/trace.go:171","msg":"trace[696380463] transaction","detail":"{read_only:false; response_revision:5072; number_of_response:1; }","duration":"248.901217ms","start":"2026-04-02T17:54:45.641915Z","end":"2026-04-02T17:54:45.890816Z","steps":["trace[696380463] 'process raft request' (duration: 248.816735ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:54:45.890942Z","caller":"traceutil/trace.go:171","msg":"trace[437997568] linearizableReadLoop","detail":"{readStateIndex:5261; appliedIndex:5260; }","duration":"281.301098ms","start":"2026-04-02T17:54:45.609598Z","end":"2026-04-02T17:54:45.890899Z","steps":["trace[437997568] 'read index received' (duration: 118.154812ms)","trace[437997568] 'applied index is now lower than readState.Index' (duration: 163.141566ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:54:45.89109Z","caller":"traceutil/trace.go:171","msg":"trace[409060885] transaction","detail":"{read_only:false; response_revision:5071; number_of_response:1; }","duration":"288.994453ms","start":"2026-04-02T17:54:45.602058Z","end":"2026-04-02T17:54:45.891052Z","steps":["trace[409060885] 'process raft request' (duration: 125.674243ms)","trace[409060885] 'compare' (duration: 162.574802ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:54:45.891252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.275794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:54:45.891308Z","caller":"traceutil/trace.go:171","msg":"trace[1517356462] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:5072; }","duration":"123.372997ms","start":"2026-04-02T17:54:45.767922Z","end":"2026-04-02T17:54:45.891295Z","steps":["trace[1517356462] 'agreement among raft nodes before linearized reading' (duration: 123.257694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:54:45.891361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.061329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-47d551e8-b239-4cd4-aec1-9fcb8f334955\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-02T17:54:45.891462Z","caller":"traceutil/trace.go:171","msg":"trace[1866322593] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-47d551e8-b239-4cd4-aec1-9fcb8f334955; range_end:; response_count:1; response_revision:5072; }","duration":"198.239963ms","start":"2026-04-02T17:54:45.693198Z","end":"2026-04-02T17:54:45.891438Z","steps":["trace[1866322593] 'agreement among raft nodes before linearized reading' (duration: 197.951536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:54:45.891467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.845761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-04-02T17:54:45.891575Z","caller":"traceutil/trace.go:171","msg":"trace[1913397215] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5072; }","duration":"281.993655ms","start":"2026-04-02T17:54:45.609558Z","end":"2026-04-02T17:54:45.891552Z","steps":["trace[1913397215] 'agreement among raft nodes before linearized reading' (duration: 281.511624ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:54:57.23363Z","caller":"traceutil/trace.go:171","msg":"trace[1402517745] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"119.890093ms","start":"2026-04-02T17:54:57.113713Z","end":"2026-04-02T17:54:57.233603Z","steps":["trace[1402517745] 'process raft request' (duration: 119.655638ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:54:57.847057Z","caller":"traceutil/trace.go:171","msg":"trace[1345826128] transaction","detail":"{read_only:false; response_revision:5305; number_of_response:1; }","duration":"258.804906ms","start":"2026-04-02T17:54:57.588224Z","end":"2026-04-02T17:54:57.847028Z","steps":["trace[1345826128] 'process raft request' (duration: 258.762595ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:54:57.847442Z","caller":"traceutil/trace.go:171","msg":"trace[1063966256] transaction","detail":"{read_only:false; response_revision:5303; number_of_response:1; }","duration":"276.213116ms","start":"2026-04-02T17:54:57.571216Z","end":"2026-04-02T17:54:57.847429Z","steps":["trace[1063966256] 'process raft request' (duration: 197.428774ms)","trace[1063966256] 'compare' (duration: 78.013033ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:54:57.847615Z","caller":"traceutil/trace.go:171","msg":"trace[1673977055] transaction","detail":"{read_only:false; response_revision:5304; number_of_response:1; }","duration":"276.250876ms","start":"2026-04-02T17:54:57.571319Z","end":"2026-04-02T17:54:57.84757Z","steps":["trace[1673977055] 'process raft request' (duration: 275.620041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:55:06.020983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.908177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d1a66f1b-d5b4-4abf-a9e2-25118dbe6b5b\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-02T17:55:06.021066Z","caller":"traceutil/trace.go:171","msg":"trace[2102854966] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d1a66f1b-d5b4-4abf-a9e2-25118dbe6b5b; range_end:; response_count:1; response_revision:5471; }","duration":"220.04936ms","start":"2026-04-02T17:55:05.801Z","end":"2026-04-02T17:55:06.021049Z","steps":["trace[2102854966] 'range keys from in-memory index tree' (duration: 219.736133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:55:06.021254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.531358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2319"} {"level":"info","ts":"2026-04-02T17:55:06.02128Z","caller":"traceutil/trace.go:171","msg":"trace[50859323] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5471; }","duration":"207.568708ms","start":"2026-04-02T17:55:05.813703Z","end":"2026-04-02T17:55:06.021271Z","steps":["trace[50859323] 'range keys from in-memory index tree' (duration: 207.472676ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:55:06.106171Z","caller":"traceutil/trace.go:171","msg":"trace[319835436] transaction","detail":"{read_only:false; response_revision:5472; number_of_response:1; }","duration":"222.011477ms","start":"2026-04-02T17:55:05.884129Z","end":"2026-04-02T17:55:06.10614Z","steps":["trace[319835436] 'process raft request' (duration: 221.841433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:55:06.411264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.799491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:55:06.411644Z","caller":"traceutil/trace.go:171","msg":"trace[1698377569] transaction","detail":"{read_only:false; response_revision:5473; number_of_response:1; }","duration":"527.491958ms","start":"2026-04-02T17:55:05.884129Z","end":"2026-04-02T17:55:06.411621Z","steps":["trace[1698377569] 'process raft request' (duration: 332.146574ms)","trace[1698377569] 'compare' (duration: 194.619327ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:55:06.411726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:55:05.884104Z","time spent":"527.58338ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:55:06.412036Z","caller":"traceutil/trace.go:171","msg":"trace[73838830] linearizableReadLoop","detail":"{readStateIndex:5675; appliedIndex:5672; }","duration":"490.563677ms","start":"2026-04-02T17:55:05.921454Z","end":"2026-04-02T17:55:06.412018Z","steps":["trace[73838830] 'read index received' (duration: 184.542433ms)","trace[73838830] 'applied index is now lower than readState.Index' (duration: 306.019583ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:55:06.412107Z","caller":"traceutil/trace.go:171","msg":"trace[529023398] transaction","detail":"{read_only:false; response_revision:5474; number_of_response:1; }","duration":"524.838674ms","start":"2026-04-02T17:55:05.88726Z","end":"2026-04-02T17:55:06.412099Z","steps":["trace[529023398] 'process raft request' (duration: 524.296032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:55:06.412155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:55:05.887242Z","time spent":"524.888385ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:55:06.412323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.431924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T17:55:06.412412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.332416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T17:55:06.412441Z","caller":"traceutil/trace.go:171","msg":"trace[668808453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5474; }","duration":"329.390427ms","start":"2026-04-02T17:55:06.083043Z","end":"2026-04-02T17:55:06.412433Z","steps":["trace[668808453] 'agreement among raft nodes before linearized reading' (duration: 329.258034ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:55:06.41244Z","caller":"traceutil/trace.go:171","msg":"trace[389811736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5474; }","duration":"461.600808ms","start":"2026-04-02T17:55:05.950811Z","end":"2026-04-02T17:55:06.412412Z","steps":["trace[389811736] 'agreement among raft nodes before linearized reading' (duration: 461.423734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:55:06.412468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:55:06.083023Z","time spent":"329.440389ms","remote":"127.0.0.1:43026","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-04-02T17:55:06.412495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:55:05.950788Z","time spent":"461.69675ms","remote":"127.0.0.1:42744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T17:55:06.412606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.879013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-02T17:55:06.412665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.048169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-chunks-cache-0\" ","response":"range_response_count:1 size:4464"} {"level":"info","ts":"2026-04-02T17:55:06.412727Z","caller":"traceutil/trace.go:171","msg":"trace[1100045272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5474; }","duration":"326.017667ms","start":"2026-04-02T17:55:06.086684Z","end":"2026-04-02T17:55:06.412702Z","steps":["trace[1100045272] 'agreement among raft nodes before linearized reading' (duration: 325.7304ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:55:06.412768Z","caller":"traceutil/trace.go:171","msg":"trace[987152567] range","detail":"{range_begin:/registry/pods/monitoring/loki-chunks-cache-0; range_end:; response_count:1; response_revision:5474; }","duration":"491.300285ms","start":"2026-04-02T17:55:05.921407Z","end":"2026-04-02T17:55:06.412707Z","steps":["trace[987152567] 'agreement among raft nodes before linearized reading' (duration: 490.730901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:55:06.412708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.607387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-02T17:55:06.412874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.504547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-02T17:55:06.4129Z","caller":"traceutil/trace.go:171","msg":"trace[1488164627] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5474; }","duration":"151.591168ms","start":"2026-04-02T17:55:06.261302Z","end":"2026-04-02T17:55:06.412893Z","steps":["trace[1488164627] 'agreement among raft nodes before linearized reading' (duration: 151.506625ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:55:06.412915Z","caller":"traceutil/trace.go:171","msg":"trace[697854969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5474; }","duration":"335.844124ms","start":"2026-04-02T17:55:06.077034Z","end":"2026-04-02T17:55:06.412878Z","steps":["trace[697854969] 'agreement among raft nodes before linearized reading' (duration: 335.432853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:55:06.412946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.205157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:4788"} {"level":"warn","ts":"2026-04-02T17:55:06.412722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.807535ms","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":"warn","ts":"2026-04-02T17:55:06.412841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.601148ms","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-04-02T17:55:06.412812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:55:06.086672Z","time spent":"326.125539ms","remote":"127.0.0.1:43026","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-04-02T17:55:06.412885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:55:05.921384Z","time spent":"491.4828ms","remote":"127.0.0.1:42920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":4487,"request content":"key:\"/registry/pods/monitoring/loki-chunks-cache-0\" "} {"level":"info","ts":"2026-04-02T17:55:06.413102Z","caller":"traceutil/trace.go:171","msg":"trace[422415071] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5474; }","duration":"330.871865ms","start":"2026-04-02T17:55:06.08221Z","end":"2026-04-02T17:55:06.413081Z","steps":["trace[422415071] 'agreement among raft nodes before linearized reading' (duration: 330.563307ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:55:06.413002Z","caller":"traceutil/trace.go:171","msg":"trace[906737185] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5474; }","duration":"383.290829ms","start":"2026-04-02T17:55:06.029687Z","end":"2026-04-02T17:55:06.412978Z","steps":["trace[906737185] 'agreement among raft nodes before linearized reading' (duration: 383.190397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:55:06.413229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:55:06.029662Z","time spent":"383.549795ms","remote":"127.0.0.1:42822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":4811,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" "} {"level":"warn","ts":"2026-04-02T17:55:06.412936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.988886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gf8g9\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-04-02T17:55:06.413211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:55:06.0822Z","time spent":"330.990727ms","remote":"127.0.0.1:43026","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":"info","ts":"2026-04-02T17:55:06.413311Z","caller":"traceutil/trace.go:171","msg":"trace[809165206] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gf8g9; range_end:; response_count:1; response_revision:5474; }","duration":"372.395956ms","start":"2026-04-02T17:55:06.040895Z","end":"2026-04-02T17:55:06.41329Z","steps":["trace[809165206] 'agreement among raft nodes before linearized reading' (duration: 371.897363ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:55:06.413039Z","caller":"traceutil/trace.go:171","msg":"trace[1353540899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5474; }","duration":"213.178895ms","start":"2026-04-02T17:55:06.199835Z","end":"2026-04-02T17:55:06.413014Z","steps":["trace[1353540899] 'agreement among raft nodes before linearized reading' (duration: 212.485148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:55:06.413413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:55:06.040873Z","time spent":"372.523269ms","remote":"127.0.0.1:42920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-gf8g9\" "} {"level":"warn","ts":"2026-04-02T17:55:06.412988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:55:06.077019Z","time spent":"335.952816ms","remote":"127.0.0.1:43026","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":"info","ts":"2026-04-02T17:55:06.556279Z","caller":"traceutil/trace.go:171","msg":"trace[1044967413] linearizableReadLoop","detail":"{readStateIndex:5676; appliedIndex:5675; }","duration":"137.820626ms","start":"2026-04-02T17:55:06.418429Z","end":"2026-04-02T17:55:06.556249Z","steps":["trace[1044967413] 'read index received' (duration: 111.190783ms)","trace[1044967413] 'applied index is now lower than readState.Index' (duration: 26.628643ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:55:06.556346Z","caller":"traceutil/trace.go:171","msg":"trace[470553044] transaction","detail":"{read_only:false; response_revision:5475; number_of_response:1; }","duration":"138.688347ms","start":"2026-04-02T17:55:06.417619Z","end":"2026-04-02T17:55:06.556307Z","steps":["trace[470553044] 'process raft request' (duration: 111.986912ms)","trace[470553044] 'compare' (duration: 26.50994ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:55:06.556596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.133414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17455"} {"level":"info","ts":"2026-04-02T17:55:06.556645Z","caller":"traceutil/trace.go:171","msg":"trace[942673355] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5475; }","duration":"138.219515ms","start":"2026-04-02T17:55:06.418414Z","end":"2026-04-02T17:55:06.556633Z","steps":["trace[942673355] 'agreement among raft nodes before linearized reading' (duration: 137.98042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:55:06.854175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.983631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:55:06.854579Z","caller":"traceutil/trace.go:171","msg":"trace[2035646942] transaction","detail":"{read_only:false; response_revision:5476; number_of_response:1; }","duration":"435.543029ms","start":"2026-04-02T17:55:06.419014Z","end":"2026-04-02T17:55:06.854557Z","steps":["trace[2035646942] 'process raft request' (duration: 304.071926ms)","trace[2035646942] 'compare' (duration: 130.755066ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:55:06.854678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:55:06.418998Z","time spent":"435.638382ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:55:06.854786Z","caller":"traceutil/trace.go:171","msg":"trace[1427576483] transaction","detail":"{read_only:false; response_revision:5477; number_of_response:1; }","duration":"435.728214ms","start":"2026-04-02T17:55:06.419036Z","end":"2026-04-02T17:55:06.854765Z","steps":["trace[1427576483] 'process raft request' (duration: 435.258722ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:55:06.854752Z","caller":"traceutil/trace.go:171","msg":"trace[1583954551] transaction","detail":"{read_only:false; response_revision:5480; number_of_response:1; }","duration":"432.170998ms","start":"2026-04-02T17:55:06.422555Z","end":"2026-04-02T17:55:06.854726Z","steps":["trace[1583954551] 'process raft request' (duration: 431.938952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:55:06.854898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:55:06.419022Z","time spent":"435.816585ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:55:06.854944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:55:06.422544Z","time spent":"432.310981ms","remote":"127.0.0.1:42920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4750,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:55:06.855006Z","caller":"traceutil/trace.go:171","msg":"trace[2080839925] linearizableReadLoop","detail":"{readStateIndex:5682; appliedIndex:5676; }","duration":"298.633525ms","start":"2026-04-02T17:55:06.556359Z","end":"2026-04-02T17:55:06.854993Z","steps":["trace[2080839925] 'read index received' (duration: 166.64341ms)","trace[2080839925] 'applied index is now lower than readState.Index' (duration: 131.988845ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:55:06.855015Z","caller":"traceutil/trace.go:171","msg":"trace[1038624414] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"332.105493ms","start":"2026-04-02T17:55:06.522899Z","end":"2026-04-02T17:55:06.855004Z","steps":["trace[1038624414] 'process raft request' (duration: 331.743425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:55:06.85515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:55:06.522868Z","time spent":"332.242266ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:55:06.855153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.712337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:55:06.855264Z","caller":"traceutil/trace.go:171","msg":"trace[1562985033] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5481; }","duration":"172.86662ms","start":"2026-04-02T17:55:06.682385Z","end":"2026-04-02T17:55:06.855251Z","steps":["trace[1562985033] 'agreement among raft nodes before linearized reading' (duration: 172.728847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:55:06.85543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.790919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80208"} {"level":"info","ts":"2026-04-02T17:55:06.855047Z","caller":"traceutil/trace.go:171","msg":"trace[1452659904] transaction","detail":"{read_only:false; response_revision:5478; number_of_response:1; }","duration":"435.502138ms","start":"2026-04-02T17:55:06.419163Z","end":"2026-04-02T17:55:06.854666Z","steps":["trace[1452659904] 'process raft request' (duration: 435.214421ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:55:06.85554Z","caller":"traceutil/trace.go:171","msg":"trace[480667057] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5481; }","duration":"436.914933ms","start":"2026-04-02T17:55:06.418601Z","end":"2026-04-02T17:55:06.855516Z","steps":["trace[480667057] 'agreement among raft nodes before linearized reading' (duration: 436.453521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:55:06.855592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:55:06.418584Z","time spent":"436.996424ms","remote":"127.0.0.1:42920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":9,"response size":80231,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-04-02T17:55:06.855691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:55:06.41915Z","time spent":"436.409411ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:55:06.855686Z","caller":"traceutil/trace.go:171","msg":"trace[568108089] transaction","detail":"{read_only:false; response_revision:5479; number_of_response:1; }","duration":"435.850007ms","start":"2026-04-02T17:55:06.419775Z","end":"2026-04-02T17:55:06.855625Z","steps":["trace[568108089] 'process raft request' (duration: 434.647747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:55:06.856133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:55:06.419767Z","time spent":"436.051582ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:56:08.249678Z","caller":"traceutil/trace.go:171","msg":"trace[956563786] transaction","detail":"{read_only:false; response_revision:6057; number_of_response:1; }","duration":"102.532315ms","start":"2026-04-02T17:56:08.147101Z","end":"2026-04-02T17:56:08.249633Z","steps":["trace[956563786] 'process raft request' (duration: 102.230067ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:56:09.290555Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3010} {"level":"info","ts":"2026-04-02T17:56:09.408605Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3010,"took":"115.648611ms","hash":3604158037,"current-db-size-bytes":58220544,"current-db-size":"58 MB","current-db-size-in-use-bytes":31350784,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-02T17:56:09.408701Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3604158037,"revision":3010,"compact-revision":-1} {"level":"info","ts":"2026-04-02T17:56:12.611463Z","caller":"traceutil/trace.go:171","msg":"trace[888515979] transaction","detail":"{read_only:false; response_revision:6084; number_of_response:1; }","duration":"105.354032ms","start":"2026-04-02T17:56:12.506081Z","end":"2026-04-02T17:56:12.611435Z","steps":["trace[888515979] 'process raft request' (duration: 105.156438ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:59:39.755705Z","caller":"traceutil/trace.go:171","msg":"trace[1039846324] transaction","detail":"{read_only:false; response_revision:7535; number_of_response:1; }","duration":"107.738266ms","start":"2026-04-02T17:59:39.647939Z","end":"2026-04-02T17:59:39.755677Z","steps":["trace[1039846324] 'process raft request' (duration: 88.974452ms)","trace[1039846324] 'compare' (duration: 18.653771ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T18:01:09.297207Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6066} {"level":"info","ts":"2026-04-02T18:01:09.397277Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6066,"took":"96.960795ms","hash":483279804,"current-db-size-bytes":58220544,"current-db-size":"58 MB","current-db-size-in-use-bytes":21725184,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-02T18:01:09.397349Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":483279804,"revision":6066,"compact-revision":3010} {"level":"info","ts":"2026-04-02T18:01:20.257314Z","caller":"traceutil/trace.go:171","msg":"trace[1486626502] transaction","detail":"{read_only:false; response_revision:8347; number_of_response:1; }","duration":"121.814051ms","start":"2026-04-02T18:01:20.135464Z","end":"2026-04-02T18:01:20.257278Z","steps":["trace[1486626502] 'process raft request' (duration: 121.614057ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:01:20.291688Z","caller":"traceutil/trace.go:171","msg":"trace[931205290] linearizableReadLoop","detail":"{readStateIndex:8642; appliedIndex:8640; }","duration":"141.623696ms","start":"2026-04-02T18:01:20.150034Z","end":"2026-04-02T18:01:20.291657Z","steps":["trace[931205290] 'read index received' (duration: 107.071918ms)","trace[931205290] 'applied index is now lower than readState.Index' (duration: 34.550408ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T18:01:20.291809Z","caller":"traceutil/trace.go:171","msg":"trace[1209445371] transaction","detail":"{read_only:false; response_revision:8348; number_of_response:1; }","duration":"142.871266ms","start":"2026-04-02T18:01:20.148895Z","end":"2026-04-02T18:01:20.291767Z","steps":["trace[1209445371] 'process raft request' (duration: 142.1902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:01:20.292021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.762328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T18:01:20.292087Z","caller":"traceutil/trace.go:171","msg":"trace[2125145576] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8348; }","duration":"103.895551ms","start":"2026-04-02T18:01:20.188179Z","end":"2026-04-02T18:01:20.292075Z","steps":["trace[2125145576] 'agreement among raft nodes before linearized reading' (duration: 103.753488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:01:20.292106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.359694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T18:01:20.292137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.911002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T18:01:20.292194Z","caller":"traceutil/trace.go:171","msg":"trace[1928239721] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8348; }","duration":"142.011795ms","start":"2026-04-02T18:01:20.150169Z","end":"2026-04-02T18:01:20.29218Z","steps":["trace[1928239721] 'agreement among raft nodes before linearized reading' (duration: 141.892112ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:01:20.292203Z","caller":"traceutil/trace.go:171","msg":"trace[403765267] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8348; }","duration":"122.507537ms","start":"2026-04-02T18:01:20.169668Z","end":"2026-04-02T18:01:20.292176Z","steps":["trace[403765267] 'agreement among raft nodes before linearized reading' (duration: 122.359714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:01:20.292169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.410275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T18:01:20.292303Z","caller":"traceutil/trace.go:171","msg":"trace[1906189894] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8348; }","duration":"122.5779ms","start":"2026-04-02T18:01:20.169687Z","end":"2026-04-02T18:01:20.292265Z","steps":["trace[1906189894] 'agreement among raft nodes before linearized reading' (duration: 122.341014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:01:20.292261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.069055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T18:01:20.292417Z","caller":"traceutil/trace.go:171","msg":"trace[2048699783] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8348; }","duration":"104.271971ms","start":"2026-04-02T18:01:20.188119Z","end":"2026-04-02T18:01:20.29239Z","steps":["trace[2048699783] 'agreement among raft nodes before linearized reading' (duration: 104.005604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:01:20.292468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.483523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T18:01:20.292576Z","caller":"traceutil/trace.go:171","msg":"trace[1406376572] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8348; }","duration":"101.616416ms","start":"2026-04-02T18:01:20.190948Z","end":"2026-04-02T18:01:20.292564Z","steps":["trace[1406376572] 'agreement among raft nodes before linearized reading' (duration: 101.476862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:01:20.292299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.37626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T18:01:20.292664Z","caller":"traceutil/trace.go:171","msg":"trace[102959459] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8348; }","duration":"121.794509ms","start":"2026-04-02T18:01:20.170835Z","end":"2026-04-02T18:01:20.292629Z","steps":["trace[102959459] 'agreement among raft nodes before linearized reading' (duration: 121.352959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:01:20.292692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.583329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T18:01:20.29277Z","caller":"traceutil/trace.go:171","msg":"trace[1180778190] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8348; }","duration":"142.718912ms","start":"2026-04-02T18:01:20.150024Z","end":"2026-04-02T18:01:20.292743Z","steps":["trace[1180778190] 'agreement among raft nodes before linearized reading' (duration: 141.905273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:01:20.293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.865281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T18:01:20.293093Z","caller":"traceutil/trace.go:171","msg":"trace[348915397] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8348; }","duration":"143.066191ms","start":"2026-04-02T18:01:20.150013Z","end":"2026-04-02T18:01:20.29308Z","steps":["trace[348915397] 'agreement among raft nodes before linearized reading' (duration: 141.82008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:01:31.492295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.436217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gf8g9\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-02T18:01:31.492417Z","caller":"traceutil/trace.go:171","msg":"trace[982566590] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gf8g9; range_end:; response_count:1; response_revision:8406; }","duration":"135.644592ms","start":"2026-04-02T18:01:31.356742Z","end":"2026-04-02T18:01:31.492387Z","steps":["trace[982566590] 'range keys from in-memory index tree' (duration: 135.204291ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:01:32.607463Z","caller":"traceutil/trace.go:171","msg":"trace[1513513773] transaction","detail":"{read_only:false; response_revision:8409; number_of_response:1; }","duration":"289.512801ms","start":"2026-04-02T18:01:32.317913Z","end":"2026-04-02T18:01:32.607426Z","steps":["trace[1513513773] 'process raft request' (duration: 289.281276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:01:32.870331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:01:32.318964Z","time spent":"551.344901ms","remote":"127.0.0.1:42768","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-02T18:01:32.870575Z","caller":"traceutil/trace.go:171","msg":"trace[1319450493] linearizableReadLoop","detail":"{readStateIndex:8706; appliedIndex:8704; }","duration":"485.609784ms","start":"2026-04-02T18:01:32.384904Z","end":"2026-04-02T18:01:32.870514Z","steps":["trace[1319450493] 'read index received' (duration: 222.374452ms)","trace[1319450493] 'applied index is now lower than readState.Index' (duration: 263.234202ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T18:01:32.870839Z","caller":"traceutil/trace.go:171","msg":"trace[768257121] transaction","detail":"{read_only:false; response_revision:8410; number_of_response:1; }","duration":"447.640633ms","start":"2026-04-02T18:01:32.423171Z","end":"2026-04-02T18:01:32.870811Z","steps":["trace[768257121] 'process raft request' (duration: 447.043838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:01:32.870915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.987042ms","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":"warn","ts":"2026-04-02T18:01:32.870986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.35888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T18:01:32.871009Z","caller":"traceutil/trace.go:171","msg":"trace[1913847949] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:8410; }","duration":"486.105445ms","start":"2026-04-02T18:01:32.384882Z","end":"2026-04-02T18:01:32.870988Z","steps":["trace[1913847949] 'agreement among raft nodes before linearized reading' (duration: 485.90748ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:01:32.871041Z","caller":"traceutil/trace.go:171","msg":"trace[758347177] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:8410; }","duration":"242.521515ms","start":"2026-04-02T18:01:32.628504Z","end":"2026-04-02T18:01:32.871025Z","steps":["trace[758347177] 'agreement among raft nodes before linearized reading' (duration: 242.393592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:01:32.871068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:01:32.384795Z","time spent":"486.262268ms","remote":"127.0.0.1:43046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":4,"response size":30,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-02T18:01:32.871827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:01:32.423141Z","time spent":"447.750155ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T18:01:34.107797Z","caller":"traceutil/trace.go:171","msg":"trace[1529091811] transaction","detail":"{read_only:false; response_revision:8417; number_of_response:1; }","duration":"480.383019ms","start":"2026-04-02T18:01:33.62738Z","end":"2026-04-02T18:01:34.107764Z","steps":["trace[1529091811] 'process raft request' (duration: 480.177784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:01:34.107991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:01:33.627344Z","time spent":"480.539563ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T18:01:34.29555Z","caller":"traceutil/trace.go:171","msg":"trace[1255994072] linearizableReadLoop","detail":"{readStateIndex:8715; appliedIndex:8713; }","duration":"345.665268ms","start":"2026-04-02T18:01:33.949854Z","end":"2026-04-02T18:01:34.295519Z","steps":["trace[1255994072] 'read index received' (duration: 157.797573ms)","trace[1255994072] 'applied index is now lower than readState.Index' (duration: 187.865565ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T18:01:34.295659Z","caller":"traceutil/trace.go:171","msg":"trace[418666953] transaction","detail":"{read_only:false; response_revision:8418; number_of_response:1; }","duration":"567.650481ms","start":"2026-04-02T18:01:33.727972Z","end":"2026-04-02T18:01:34.295623Z","steps":["trace[418666953] 'process raft request' (duration: 506.532516ms)","trace[418666953] 'compare' (duration: 60.830358ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T18:01:34.295726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.855183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T18:01:34.295775Z","caller":"traceutil/trace.go:171","msg":"trace[1292590184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8418; }","duration":"345.925144ms","start":"2026-04-02T18:01:33.949834Z","end":"2026-04-02T18:01:34.295759Z","steps":["trace[1292590184] 'agreement among raft nodes before linearized reading' (duration: 345.813942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:01:34.295813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:01:33.727939Z","time spent":"567.772994ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T18:01:34.295826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:01:33.949762Z","time spent":"346.055048ms","remote":"127.0.0.1:42744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T18:01:35.356954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.985671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T18:01:35.35708Z","caller":"traceutil/trace.go:171","msg":"trace[376111079] linearizableReadLoop","detail":"{readStateIndex:8720; appliedIndex:8719; }","duration":"219.296528ms","start":"2026-04-02T18:01:35.137764Z","end":"2026-04-02T18:01:35.35706Z","steps":["trace[376111079] 'read index received' (duration: 68.008771ms)","trace[376111079] 'applied index is now lower than readState.Index' (duration: 151.286117ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T18:01:35.357143Z","caller":"traceutil/trace.go:171","msg":"trace[1256553598] transaction","detail":"{read_only:false; response_revision:8423; number_of_response:1; }","duration":"251.626943ms","start":"2026-04-02T18:01:35.105472Z","end":"2026-04-02T18:01:35.357099Z","steps":["trace[1256553598] 'process raft request' (duration: 100.387767ms)","trace[1256553598] 'compare' (duration: 150.824646ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T18:01:35.357314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.558854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-02T18:01:35.357356Z","caller":"traceutil/trace.go:171","msg":"trace[562030744] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8423; }","duration":"219.602815ms","start":"2026-04-02T18:01:35.137743Z","end":"2026-04-02T18:01:35.357346Z","steps":["trace[562030744] 'agreement among raft nodes before linearized reading' (duration: 219.421371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:01:35.357433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.02529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T18:01:35.357494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.887654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-02T18:01:35.357525Z","caller":"traceutil/trace.go:171","msg":"trace[2100551022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8423; }","duration":"134.967406ms","start":"2026-04-02T18:01:35.22255Z","end":"2026-04-02T18:01:35.357518Z","steps":["trace[2100551022] 'agreement among raft nodes before linearized reading' (duration: 134.815362ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:01:35.357525Z","caller":"traceutil/trace.go:171","msg":"trace[195037890] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8423; }","duration":"191.174793ms","start":"2026-04-02T18:01:35.166325Z","end":"2026-04-02T18:01:35.357499Z","steps":["trace[195037890] 'agreement among raft nodes before linearized reading' (duration: 190.989279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:01:35.357673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.034887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-02T18:01:35.357706Z","caller":"traceutil/trace.go:171","msg":"trace[521712619] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8423; }","duration":"135.063438ms","start":"2026-04-02T18:01:35.222631Z","end":"2026-04-02T18:01:35.357694Z","steps":["trace[521712619] 'agreement among raft nodes before linearized reading' (duration: 134.995476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:01:35.357842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.054297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T18:01:35.357869Z","caller":"traceutil/trace.go:171","msg":"trace[685070879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8423; }","duration":"170.129819ms","start":"2026-04-02T18:01:35.187733Z","end":"2026-04-02T18:01:35.357863Z","steps":["trace[685070879] 'agreement among raft nodes before linearized reading' (duration: 170.000056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:01:35.59344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.744395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T18:01:35.593668Z","caller":"traceutil/trace.go:171","msg":"trace[629832364] linearizableReadLoop","detail":"{readStateIndex:8723; appliedIndex:8720; }","duration":"221.547472ms","start":"2026-04-02T18:01:35.372098Z","end":"2026-04-02T18:01:35.593645Z","steps":["trace[629832364] 'read index received' (duration: 53.369589ms)","trace[629832364] 'applied index is now lower than readState.Index' (duration: 168.176572ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T18:01:35.593702Z","caller":"traceutil/trace.go:171","msg":"trace[153714362] transaction","detail":"{read_only:false; response_revision:8425; number_of_response:1; }","duration":"229.394361ms","start":"2026-04-02T18:01:35.364262Z","end":"2026-04-02T18:01:35.593657Z","steps":["trace[153714362] 'process raft request' (duration: 93.308218ms)","trace[153714362] 'compare' (duration: 135.56551ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T18:01:35.593809Z","caller":"traceutil/trace.go:171","msg":"trace[1838958533] transaction","detail":"{read_only:false; response_revision:8426; number_of_response:1; }","duration":"229.422602ms","start":"2026-04-02T18:01:35.364314Z","end":"2026-04-02T18:01:35.593736Z","steps":["trace[1838958533] 'process raft request' (duration: 229.263828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:01:35.594344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.207409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-04-02T18:01:35.59446Z","caller":"traceutil/trace.go:171","msg":"trace[1550201133] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8426; }","duration":"222.350162ms","start":"2026-04-02T18:01:35.37208Z","end":"2026-04-02T18:01:35.59443Z","steps":["trace[1550201133] 'agreement among raft nodes before linearized reading' (duration: 221.647334ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:01:36.300176Z","caller":"traceutil/trace.go:171","msg":"trace[1626437842] transaction","detail":"{read_only:false; response_revision:8427; number_of_response:1; }","duration":"184.476334ms","start":"2026-04-02T18:01:36.115671Z","end":"2026-04-02T18:01:36.300147Z","steps":["trace[1626437842] 'process raft request' (duration: 184.286708ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:01:36.560041Z","caller":"traceutil/trace.go:171","msg":"trace[1048956714] transaction","detail":"{read_only:false; response_revision:8428; number_of_response:1; }","duration":"258.678623ms","start":"2026-04-02T18:01:36.301316Z","end":"2026-04-02T18:01:36.559995Z","steps":["trace[1048956714] 'process raft request' (duration: 258.456028ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:01.227362Z","caller":"traceutil/trace.go:171","msg":"trace[1233237301] linearizableReadLoop","detail":"{readStateIndex:8918; appliedIndex:8917; }","duration":"210.597383ms","start":"2026-04-02T18:02:01.016738Z","end":"2026-04-02T18:02:01.227335Z","steps":["trace[1233237301] 'read index received' (duration: 210.338316ms)","trace[1233237301] 'applied index is now lower than readState.Index' (duration: 258.337µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T18:02:01.227686Z","caller":"traceutil/trace.go:171","msg":"trace[1841296946] transaction","detail":"{read_only:false; response_revision:8614; number_of_response:1; }","duration":"224.760663ms","start":"2026-04-02T18:02:01.002915Z","end":"2026-04-02T18:02:01.227675Z","steps":["trace[1841296946] 'process raft request' (duration: 224.264761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:01.227979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.225728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/rook-ceph/rook-ceph-csi-config\" ","response":"range_response_count:1 size:512"} {"level":"info","ts":"2026-04-02T18:02:01.228016Z","caller":"traceutil/trace.go:171","msg":"trace[1258738836] range","detail":"{range_begin:/registry/configmaps/rook-ceph/rook-ceph-csi-config; range_end:; response_count:1; response_revision:8614; }","duration":"211.34291ms","start":"2026-04-02T18:02:01.016665Z","end":"2026-04-02T18:02:01.228008Z","steps":["trace[1258738836] 'agreement among raft nodes before linearized reading' (duration: 211.191877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:01.228193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.897317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-02T18:02:01.228218Z","caller":"traceutil/trace.go:171","msg":"trace[1726021482] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8614; }","duration":"118.974638ms","start":"2026-04-02T18:02:01.109237Z","end":"2026-04-02T18:02:01.228212Z","steps":["trace[1726021482] 'agreement among raft nodes before linearized reading' (duration: 118.899286ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:02.520881Z","caller":"traceutil/trace.go:171","msg":"trace[1264014609] transaction","detail":"{read_only:false; response_revision:8623; number_of_response:1; }","duration":"102.650608ms","start":"2026-04-02T18:02:02.418081Z","end":"2026-04-02T18:02:02.520731Z","steps":["trace[1264014609] 'process raft request' (duration: 42.483667ms)","trace[1264014609] 'compare' (duration: 59.776982ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T18:02:04.08101Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113416600534672,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T18:02:04.159176Z","caller":"traceutil/trace.go:171","msg":"trace[1047268470] linearizableReadLoop","detail":"{readStateIndex:8933; appliedIndex:8932; }","duration":"579.110548ms","start":"2026-04-02T18:02:03.580032Z","end":"2026-04-02T18:02:04.159143Z","steps":["trace[1047268470] 'read index received' (duration: 578.913903ms)","trace[1047268470] 'applied index is now lower than readState.Index' (duration: 195.305µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T18:02:04.159572Z","caller":"traceutil/trace.go:171","msg":"trace[650763921] transaction","detail":"{read_only:false; response_revision:8628; number_of_response:1; }","duration":"623.157632ms","start":"2026-04-02T18:02:03.536391Z","end":"2026-04-02T18:02:04.159549Z","steps":["trace[650763921] 'process raft request' (duration: 622.577639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:04.159745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:03.536369Z","time spent":"623.260575ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T18:02:04.160127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.099362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-02T18:02:04.160178Z","caller":"traceutil/trace.go:171","msg":"trace[150502556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8628; }","duration":"580.184654ms","start":"2026-04-02T18:02:03.57998Z","end":"2026-04-02T18:02:04.160165Z","steps":["trace[150502556] 'agreement among raft nodes before linearized reading' (duration: 580.02697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:04.16022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:03.579956Z","time spent":"580.254485ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T18:02:04.160464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.957597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-02T18:02:04.161038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.153725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T18:02:04.161054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.931785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T18:02:04.161137Z","caller":"traceutil/trace.go:171","msg":"trace[1496992483] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:8628; }","duration":"264.320049ms","start":"2026-04-02T18:02:03.896794Z","end":"2026-04-02T18:02:04.161114Z","steps":["trace[1496992483] 'agreement among raft nodes before linearized reading' (duration: 264.088494ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:04.160509Z","caller":"traceutil/trace.go:171","msg":"trace[1699684787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8628; }","duration":"397.050568ms","start":"2026-04-02T18:02:03.763445Z","end":"2026-04-02T18:02:04.160496Z","steps":["trace[1699684787] 'agreement among raft nodes before linearized reading' (duration: 396.955056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:04.161271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:03.763417Z","time spent":"397.836866ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T18:02:04.161337Z","caller":"traceutil/trace.go:171","msg":"trace[1554707745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8628; }","duration":"212.12934ms","start":"2026-04-02T18:02:03.949013Z","end":"2026-04-02T18:02:04.161143Z","steps":["trace[1554707745] 'agreement among raft nodes before linearized reading' (duration: 211.905964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:04.161354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.526118ms","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-04-02T18:02:04.161454Z","caller":"traceutil/trace.go:171","msg":"trace[1969254262] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8628; }","duration":"394.66332ms","start":"2026-04-02T18:02:03.766766Z","end":"2026-04-02T18:02:04.161429Z","steps":["trace[1969254262] 'agreement among raft nodes before linearized reading' (duration: 394.302752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:04.161513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:03.766759Z","time spent":"394.745923ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T18:02:04.161877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.252063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gf8g9\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-02T18:02:04.161986Z","caller":"traceutil/trace.go:171","msg":"trace[881277962] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gf8g9; range_end:; response_count:1; response_revision:8628; }","duration":"369.400046ms","start":"2026-04-02T18:02:03.792562Z","end":"2026-04-02T18:02:04.161962Z","steps":["trace[881277962] 'agreement among raft nodes before linearized reading' (duration: 369.065008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:04.162043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:03.792548Z","time spent":"369.485058ms","remote":"127.0.0.1:42920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-gf8g9\" "} {"level":"warn","ts":"2026-04-02T18:02:05.599171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:04.511821Z","time spent":"1.087331808s","remote":"127.0.0.1:53858","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-02T18:02:05.600182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113577977s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T18:02:05.600328Z","caller":"traceutil/trace.go:171","msg":"trace[1560607420] transaction","detail":"{read_only:false; response_revision:8629; number_of_response:1; }","duration":"1.434419789s","start":"2026-04-02T18:02:04.165882Z","end":"2026-04-02T18:02:05.600302Z","steps":["trace[1560607420] 'process raft request' (duration: 320.627868ms)","trace[1560607420] 'compare' (duration: 1.113101324s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T18:02:05.600409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:04.16585Z","time spent":"1.434524062s","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T18:02:05.887603Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.401139893s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-02T18:02:06.11182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.78727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T18:02:06.112419Z","caller":"traceutil/trace.go:171","msg":"trace[559262637] transaction","detail":"{read_only:false; response_revision:8630; number_of_response:1; }","duration":"1.94551622s","start":"2026-04-02T18:02:04.166819Z","end":"2026-04-02T18:02:06.112335Z","steps":["trace[559262637] 'process raft request' (duration: 1.721097805s)","trace[559262637] 'compare' (duration: 223.319428ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T18:02:06.112467Z","caller":"traceutil/trace.go:171","msg":"trace[559590861] transaction","detail":"{read_only:false; response_revision:8631; number_of_response:1; }","duration":"1.945363336s","start":"2026-04-02T18:02:04.167081Z","end":"2026-04-02T18:02:06.112444Z","steps":["trace[559590861] 'process raft request' (duration: 1.944875964s)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:06.112717Z","caller":"traceutil/trace.go:171","msg":"trace[1885862310] transaction","detail":"{read_only:false; response_revision:8632; number_of_response:1; }","duration":"1.745997502s","start":"2026-04-02T18:02:04.366697Z","end":"2026-04-02T18:02:06.112695Z","steps":["trace[1885862310] 'process raft request' (duration: 1.745676225s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:06.11279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:04.366666Z","time spent":"1.746090935s","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T18:02:06.112826Z","caller":"traceutil/trace.go:171","msg":"trace[162980018] linearizableReadLoop","detail":"{readStateIndex:8935; appliedIndex:8933; }","duration":"1.945890388s","start":"2026-04-02T18:02:04.166898Z","end":"2026-04-02T18:02:06.112789Z","steps":["trace[162980018] 'read index received' (duration: 319.633844ms)","trace[162980018] 'applied index is now lower than readState.Index' (duration: 1.626229034s)"],"step_count":2} {"level":"info","ts":"2026-04-02T18:02:06.112893Z","caller":"traceutil/trace.go:171","msg":"trace[619394962] transaction","detail":"{read_only:false; response_revision:8633; number_of_response:1; }","duration":"1.585754764s","start":"2026-04-02T18:02:04.527104Z","end":"2026-04-02T18:02:06.112859Z","steps":["trace[619394962] 'process raft request' (duration: 1.58558713s)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:06.113048Z","caller":"traceutil/trace.go:171","msg":"trace[1363522464] transaction","detail":"{read_only:false; response_revision:8634; number_of_response:1; }","duration":"1.420589519s","start":"2026-04-02T18:02:04.692435Z","end":"2026-04-02T18:02:06.113024Z","steps":["trace[1363522464] 'process raft request' (duration: 1.420363184s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:06.112635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:04.166799Z","time spent":"1.945733695s","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T18:02:06.112701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:04.16706Z","time spent":"1.94551481s","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T18:02:06.113173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:04.527064Z","time spent":"1.58598598s","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T18:02:06.113213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:04.692401Z","time spent":"1.420730492s","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T18:02:06.113373Z","caller":"traceutil/trace.go:171","msg":"trace[792873215] transaction","detail":"{read_only:false; response_revision:8635; number_of_response:1; }","duration":"1.34546688s","start":"2026-04-02T18:02:04.76789Z","end":"2026-04-02T18:02:06.113357Z","steps":["trace[792873215] 'process raft request' (duration: 1.3450428s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:06.113489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:04.767858Z","time spent":"1.345567952s","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T18:02:06.113862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.273178498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T18:02:06.113935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.467019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-02T18:02:06.11391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.946980405s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228863"} {"level":"info","ts":"2026-04-02T18:02:06.113978Z","caller":"traceutil/trace.go:171","msg":"trace[864653188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8636; }","duration":"795.538521ms","start":"2026-04-02T18:02:05.318428Z","end":"2026-04-02T18:02:06.113966Z","steps":["trace[864653188] 'agreement among raft nodes before linearized reading' (duration: 795.367457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:06.114011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:05.31842Z","time spent":"795.584412ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T18:02:06.114076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229600676s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T18:02:06.114039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.824543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T18:02:06.114126Z","caller":"traceutil/trace.go:171","msg":"trace[1939668996] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:8636; }","duration":"1.229708568s","start":"2026-04-02T18:02:04.884403Z","end":"2026-04-02T18:02:06.114112Z","steps":["trace[1939668996] 'agreement among raft nodes before linearized reading' (duration: 1.229636906s)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:06.114141Z","caller":"traceutil/trace.go:171","msg":"trace[1631507143] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:8636; }","duration":"966.924885ms","start":"2026-04-02T18:02:05.1472Z","end":"2026-04-02T18:02:06.114124Z","steps":["trace[1631507143] 'agreement among raft nodes before linearized reading' (duration: 966.775431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:06.114203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:05.147145Z","time spent":"967.043247ms","remote":"127.0.0.1:32858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":20,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-04-02T18:02:06.114181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23107594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T18:02:06.11428Z","caller":"traceutil/trace.go:171","msg":"trace[1281497839] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:8636; }","duration":"1.231254534s","start":"2026-04-02T18:02:04.883004Z","end":"2026-04-02T18:02:06.114259Z","steps":["trace[1281497839] 'agreement among raft nodes before linearized reading' (duration: 1.23106362s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:06.114343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:04.882977Z","time spent":"1.231354397s","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":30,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"info","ts":"2026-04-02T18:02:06.114392Z","caller":"traceutil/trace.go:171","msg":"trace[410660928] transaction","detail":"{read_only:false; response_revision:8636; number_of_response:1; }","duration":"872.393641ms","start":"2026-04-02T18:02:05.241981Z","end":"2026-04-02T18:02:06.114374Z","steps":["trace[410660928] 'process raft request' (duration: 871.303655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:06.114411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164526757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T18:02:06.114459Z","caller":"traceutil/trace.go:171","msg":"trace[367938093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8636; }","duration":"1.164571968s","start":"2026-04-02T18:02:04.949875Z","end":"2026-04-02T18:02:06.114447Z","steps":["trace[367938093] 'agreement among raft nodes before linearized reading' (duration: 1.164501546s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:06.114488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:04.949817Z","time spent":"1.164666169s","remote":"127.0.0.1:42744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T18:02:06.114493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:05.241941Z","time spent":"872.503093ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T18:02:06.114169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:04.88436Z","time spent":"1.22979993s","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"info","ts":"2026-04-02T18:02:06.113944Z","caller":"traceutil/trace.go:171","msg":"trace[1780212975] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:8636; }","duration":"1.27326487s","start":"2026-04-02T18:02:04.84066Z","end":"2026-04-02T18:02:06.113925Z","steps":["trace[1780212975] 'agreement among raft nodes before linearized reading' (duration: 1.273084286s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:06.114701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.526089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T18:02:06.114758Z","caller":"traceutil/trace.go:171","msg":"trace[937507002] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:8636; }","duration":"521.626031ms","start":"2026-04-02T18:02:05.593115Z","end":"2026-04-02T18:02:06.114741Z","steps":["trace[937507002] 'agreement among raft nodes before linearized reading' (duration: 521.537549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:06.114806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:05.593093Z","time spent":"521.700743ms","remote":"127.0.0.1:55284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-04-02T18:02:06.114679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:04.840583Z","time spent":"1.27407732s","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"info","ts":"2026-04-02T18:02:06.114005Z","caller":"traceutil/trace.go:171","msg":"trace[1510442061] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8634; }","duration":"1.947112758s","start":"2026-04-02T18:02:04.166872Z","end":"2026-04-02T18:02:06.113985Z","steps":["trace[1510442061] 'agreement among raft nodes before linearized reading' (duration: 1.946047702s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:06.11493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:04.16686Z","time spent":"1.948042219s","remote":"127.0.0.1:42920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":26,"response size":228886,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-04-02T18:02:06.611049Z","caller":"traceutil/trace.go:171","msg":"trace[1508381781] linearizableReadLoop","detail":"{readStateIndex:8942; appliedIndex:8941; }","duration":"487.410612ms","start":"2026-04-02T18:02:06.12361Z","end":"2026-04-02T18:02:06.61102Z","steps":["trace[1508381781] 'read index received' (duration: 487.209927ms)","trace[1508381781] 'applied index is now lower than readState.Index' (duration: 199.245µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T18:02:06.611211Z","caller":"traceutil/trace.go:171","msg":"trace[1487643023] transaction","detail":"{read_only:false; response_revision:8637; number_of_response:1; }","duration":"490.172229ms","start":"2026-04-02T18:02:06.121025Z","end":"2026-04-02T18:02:06.611197Z","steps":["trace[1487643023] 'process raft request' (duration: 489.801029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:06.611448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:06.121002Z","time spent":"490.2466ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T18:02:06.611664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.046987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T18:02:06.611769Z","caller":"traceutil/trace.go:171","msg":"trace[1880169190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8637; }","duration":"488.154609ms","start":"2026-04-02T18:02:06.123584Z","end":"2026-04-02T18:02:06.611739Z","steps":["trace[1880169190] 'agreement among raft nodes before linearized reading' (duration: 488.025016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:06.61184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:06.123569Z","time spent":"488.257552ms","remote":"127.0.0.1:42744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T18:02:06.611843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.431755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-04-02T18:02:06.611882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.836631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-02T18:02:06.611976Z","caller":"traceutil/trace.go:171","msg":"trace[446358626] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8637; }","duration":"280.617749ms","start":"2026-04-02T18:02:06.331324Z","end":"2026-04-02T18:02:06.611942Z","steps":["trace[446358626] 'agreement among raft nodes before linearized reading' (duration: 280.22931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:06.611508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.599786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T18:02:06.612075Z","caller":"traceutil/trace.go:171","msg":"trace[1602478664] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8637; }","duration":"447.029806ms","start":"2026-04-02T18:02:06.164982Z","end":"2026-04-02T18:02:06.612012Z","steps":["trace[1602478664] 'agreement among raft nodes before linearized reading' (duration: 446.621776ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:06.61232Z","caller":"traceutil/trace.go:171","msg":"trace[899591714] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:8637; }","duration":"475.472917ms","start":"2026-04-02T18:02:06.136829Z","end":"2026-04-02T18:02:06.612302Z","steps":["trace[899591714] 'agreement among raft nodes before linearized reading' (duration: 474.551365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:06.612353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:06.164934Z","time spent":"447.399834ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T18:02:06.612749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:06.136818Z","time spent":"475.61828ms","remote":"127.0.0.1:43002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":2,"response size":30,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-04-02T18:02:08.934339Z","caller":"traceutil/trace.go:171","msg":"trace[1208265298] linearizableReadLoop","detail":"{readStateIndex:8954; appliedIndex:8953; }","duration":"118.467267ms","start":"2026-04-02T18:02:08.815846Z","end":"2026-04-02T18:02:08.934314Z","steps":["trace[1208265298] 'read index received' (duration: 118.292723ms)","trace[1208265298] 'applied index is now lower than readState.Index' (duration: 173.334µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T18:02:08.934446Z","caller":"traceutil/trace.go:171","msg":"trace[1383915825] transaction","detail":"{read_only:false; response_revision:8648; number_of_response:1; }","duration":"224.497406ms","start":"2026-04-02T18:02:08.709912Z","end":"2026-04-02T18:02:08.93441Z","steps":["trace[1383915825] 'process raft request' (duration: 224.23869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:08.9345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.62048ms","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":"info","ts":"2026-04-02T18:02:08.934561Z","caller":"traceutil/trace.go:171","msg":"trace[2031613198] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:8648; }","duration":"118.762263ms","start":"2026-04-02T18:02:08.815783Z","end":"2026-04-02T18:02:08.934545Z","steps":["trace[2031613198] 'agreement among raft nodes before linearized reading' (duration: 118.64018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:09.293211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.434667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T18:02:09.293357Z","caller":"traceutil/trace.go:171","msg":"trace[689612409] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:8648; }","duration":"184.640692ms","start":"2026-04-02T18:02:09.108671Z","end":"2026-04-02T18:02:09.293312Z","steps":["trace[689612409] 'count revisions from in-memory index tree' (duration: 184.260253ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:11.841625Z","caller":"traceutil/trace.go:171","msg":"trace[1923476328] linearizableReadLoop","detail":"{readStateIndex:8968; appliedIndex:8967; }","duration":"192.330096ms","start":"2026-04-02T18:02:11.649266Z","end":"2026-04-02T18:02:11.841596Z","steps":["trace[1923476328] 'read index received' (duration: 192.038619ms)","trace[1923476328] 'applied index is now lower than readState.Index' (duration: 290.077µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T18:02:11.841887Z","caller":"traceutil/trace.go:171","msg":"trace[28640699] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"202.442587ms","start":"2026-04-02T18:02:11.639432Z","end":"2026-04-02T18:02:11.841875Z","steps":["trace[28640699] 'process raft request' (duration: 201.988357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:11.842138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.847568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T18:02:11.842179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.267243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-02T18:02:11.842234Z","caller":"traceutil/trace.go:171","msg":"trace[816833198] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:8662; }","duration":"192.995161ms","start":"2026-04-02T18:02:11.649218Z","end":"2026-04-02T18:02:11.842213Z","steps":["trace[816833198] 'agreement among raft nodes before linearized reading' (duration: 192.721415ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:11.842266Z","caller":"traceutil/trace.go:171","msg":"trace[1888350781] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8662; }","duration":"131.420557ms","start":"2026-04-02T18:02:11.710827Z","end":"2026-04-02T18:02:11.842247Z","steps":["trace[1888350781] 'agreement among raft nodes before linearized reading' (duration: 131.12999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:12.376213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.074054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-02T18:02:12.376317Z","caller":"traceutil/trace.go:171","msg":"trace[1740821376] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8662; }","duration":"454.256869ms","start":"2026-04-02T18:02:11.922038Z","end":"2026-04-02T18:02:12.376295Z","steps":["trace[1740821376] 'range keys from in-memory index tree' (duration: 453.819588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:12.376382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:11.922008Z","time spent":"454.362592ms","remote":"127.0.0.1:42920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-02T18:02:12.376537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.469931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T18:02:12.37668Z","caller":"traceutil/trace.go:171","msg":"trace[1138073913] transaction","detail":"{read_only:false; response_revision:8663; number_of_response:1; }","duration":"223.871282ms","start":"2026-04-02T18:02:12.152783Z","end":"2026-04-02T18:02:12.376655Z","steps":["trace[1138073913] 'process raft request' (duration: 122.128715ms)","trace[1138073913] 'compare' (duration: 101.332127ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T18:02:12.563954Z","caller":"traceutil/trace.go:171","msg":"trace[1254039260] linearizableReadLoop","detail":"{readStateIndex:8972; appliedIndex:8968; }","duration":"385.096852ms","start":"2026-04-02T18:02:12.17883Z","end":"2026-04-02T18:02:12.563927Z","steps":["trace[1254039260] 'read index received' (duration: 96.05301ms)","trace[1254039260] 'applied index is now lower than readState.Index' (duration: 289.043022ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T18:02:12.564223Z","caller":"traceutil/trace.go:171","msg":"trace[198510370] transaction","detail":"{read_only:false; response_revision:8664; number_of_response:1; }","duration":"388.818051ms","start":"2026-04-02T18:02:12.175397Z","end":"2026-04-02T18:02:12.564215Z","steps":["trace[198510370] 'process raft request' (duration: 388.312718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:12.564325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:12.175369Z","time spent":"388.878843ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T18:02:12.564484Z","caller":"traceutil/trace.go:171","msg":"trace[1854274791] transaction","detail":"{read_only:false; response_revision:8665; number_of_response:1; }","duration":"387.92055ms","start":"2026-04-02T18:02:12.176558Z","end":"2026-04-02T18:02:12.564478Z","steps":["trace[1854274791] 'process raft request' (duration: 387.321106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:12.564532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:12.176549Z","time spent":"387.949301ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T18:02:12.564614Z","caller":"traceutil/trace.go:171","msg":"trace[1832998470] transaction","detail":"{read_only:false; response_revision:8666; number_of_response:1; }","duration":"386.537766ms","start":"2026-04-02T18:02:12.17807Z","end":"2026-04-02T18:02:12.564608Z","steps":["trace[1832998470] 'process raft request' (duration: 385.83104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:12.564651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:12.178049Z","time spent":"386.580758ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T18:02:12.564797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.980304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T18:02:12.564828Z","caller":"traceutil/trace.go:171","msg":"trace[1919740648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8666; }","duration":"386.048275ms","start":"2026-04-02T18:02:12.178762Z","end":"2026-04-02T18:02:12.56481Z","steps":["trace[1919740648] 'agreement among raft nodes before linearized reading' (duration: 385.951313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:12.564845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:12.17872Z","time spent":"386.122347ms","remote":"127.0.0.1:43026","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-04-02T18:02:12.564961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.324265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gf8g9\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-02T18:02:12.564976Z","caller":"traceutil/trace.go:171","msg":"trace[1686846359] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gf8g9; range_end:; response_count:1; response_revision:8666; }","duration":"369.375516ms","start":"2026-04-02T18:02:12.195596Z","end":"2026-04-02T18:02:12.564972Z","steps":["trace[1686846359] 'agreement among raft nodes before linearized reading' (duration: 369.324505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:12.564991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:12.195577Z","time spent":"369.408516ms","remote":"127.0.0.1:42920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-gf8g9\" "} {"level":"warn","ts":"2026-04-02T18:02:12.565202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.043353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-02T18:02:12.565217Z","caller":"traceutil/trace.go:171","msg":"trace[513549199] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8666; }","duration":"368.079654ms","start":"2026-04-02T18:02:12.197132Z","end":"2026-04-02T18:02:12.565212Z","steps":["trace[513549199] 'agreement among raft nodes before linearized reading' (duration: 368.034683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:12.56523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:12.19712Z","time spent":"368.106455ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T18:02:12.565329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.950261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-02T18:02:12.565419Z","caller":"traceutil/trace.go:171","msg":"trace[2140074701] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:8666; }","duration":"244.126966ms","start":"2026-04-02T18:02:12.321271Z","end":"2026-04-02T18:02:12.565398Z","steps":["trace[2140074701] 'agreement among raft nodes before linearized reading' (duration: 243.831819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:12.565671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.415528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19382"} {"level":"info","ts":"2026-04-02T18:02:12.565766Z","caller":"traceutil/trace.go:171","msg":"trace[1856259186] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8666; }","duration":"187.553831ms","start":"2026-04-02T18:02:12.378186Z","end":"2026-04-02T18:02:12.56574Z","steps":["trace[1856259186] 'agreement among raft nodes before linearized reading' (duration: 187.272535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:12.565673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.033955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T18:02:12.565955Z","caller":"traceutil/trace.go:171","msg":"trace[1086115221] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:8666; }","duration":"224.359243ms","start":"2026-04-02T18:02:12.341578Z","end":"2026-04-02T18:02:12.565937Z","steps":["trace[1086115221] 'agreement among raft nodes before linearized reading' (duration: 224.034415ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:16.705245Z","caller":"traceutil/trace.go:171","msg":"trace[414008281] transaction","detail":"{read_only:false; response_revision:8716; number_of_response:1; }","duration":"119.981303ms","start":"2026-04-02T18:02:16.58522Z","end":"2026-04-02T18:02:16.705202Z","steps":["trace[414008281] 'process raft request' (duration: 111.390657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:16.705473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.567462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-02T18:02:16.705538Z","caller":"traceutil/trace.go:171","msg":"trace[1499383575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8717; }","duration":"116.693845ms","start":"2026-04-02T18:02:16.588829Z","end":"2026-04-02T18:02:16.705523Z","steps":["trace[1499383575] 'agreement among raft nodes before linearized reading' (duration: 116.5003ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:16.705547Z","caller":"traceutil/trace.go:171","msg":"trace[417028611] transaction","detail":"{read_only:false; response_revision:8717; number_of_response:1; }","duration":"119.675807ms","start":"2026-04-02T18:02:16.585851Z","end":"2026-04-02T18:02:16.705527Z","steps":["trace[417028611] 'process raft request' (duration: 119.259627ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:16.705263Z","caller":"traceutil/trace.go:171","msg":"trace[1790471706] linearizableReadLoop","detail":"{readStateIndex:9027; appliedIndex:9024; }","duration":"116.353197ms","start":"2026-04-02T18:02:16.588879Z","end":"2026-04-02T18:02:16.705232Z","steps":["trace[1790471706] 'read index received' (duration: 292.747µs)","trace[1790471706] 'applied index is now lower than readState.Index' (duration: 116.05917ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T18:02:16.705953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.237006ms","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-04-02T18:02:16.706017Z","caller":"traceutil/trace.go:171","msg":"trace[2091412335] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8717; }","duration":"114.38606ms","start":"2026-04-02T18:02:16.591613Z","end":"2026-04-02T18:02:16.705999Z","steps":["trace[2091412335] 'agreement among raft nodes before linearized reading' (duration: 114.146374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:16.706033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.835906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gf8g9\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-02T18:02:16.706161Z","caller":"traceutil/trace.go:171","msg":"trace[1449164697] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gf8g9; range_end:; response_count:1; response_revision:8717; }","duration":"109.027341ms","start":"2026-04-02T18:02:16.597107Z","end":"2026-04-02T18:02:16.706134Z","steps":["trace[1449164697] 'agreement among raft nodes before linearized reading' (duration: 108.690933ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:19.24481Z","caller":"traceutil/trace.go:171","msg":"trace[335604400] transaction","detail":"{read_only:false; response_revision:8742; number_of_response:1; }","duration":"199.718882ms","start":"2026-04-02T18:02:19.045059Z","end":"2026-04-02T18:02:19.244778Z","steps":["trace[335604400] 'process raft request' (duration: 134.47104ms)","trace[335604400] 'compare' (duration: 64.906374ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T18:02:19.395645Z","caller":"traceutil/trace.go:171","msg":"trace[2041462324] transaction","detail":"{read_only:false; response_revision:8744; number_of_response:1; }","duration":"131.820797ms","start":"2026-04-02T18:02:19.263796Z","end":"2026-04-02T18:02:19.395617Z","steps":["trace[2041462324] 'process raft request' (duration: 107.587887ms)","trace[2041462324] 'compare' (duration: 24.101317ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T18:02:20.171666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.745637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T18:02:20.171774Z","caller":"traceutil/trace.go:171","msg":"trace[701792272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8747; }","duration":"220.971932ms","start":"2026-04-02T18:02:19.950779Z","end":"2026-04-02T18:02:20.171751Z","steps":["trace[701792272] 'range keys from in-memory index tree' (duration: 220.646884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:20.171897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.799894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-02T18:02:20.17199Z","caller":"traceutil/trace.go:171","msg":"trace[366704781] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8747; }","duration":"285.949438ms","start":"2026-04-02T18:02:19.886019Z","end":"2026-04-02T18:02:20.171968Z","steps":["trace[366704781] 'range keys from in-memory index tree' (duration: 285.598819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:20.17206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.339572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3672"} {"level":"warn","ts":"2026-04-02T18:02:20.172079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.007821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-04-02T18:02:20.17215Z","caller":"traceutil/trace.go:171","msg":"trace[1332133722] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:8747; }","duration":"277.423073ms","start":"2026-04-02T18:02:19.894687Z","end":"2026-04-02T18:02:20.17211Z","steps":["trace[1332133722] 'range keys from in-memory index tree' (duration: 277.240719ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:20.172176Z","caller":"traceutil/trace.go:171","msg":"trace[1616553483] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8747; }","duration":"145.217486ms","start":"2026-04-02T18:02:20.026935Z","end":"2026-04-02T18:02:20.172152Z","steps":["trace[1616553483] 'range keys from in-memory index tree' (duration: 144.712704ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:20.46237Z","caller":"traceutil/trace.go:171","msg":"trace[602321999] transaction","detail":"{read_only:false; response_revision:8749; number_of_response:1; }","duration":"276.409079ms","start":"2026-04-02T18:02:20.185908Z","end":"2026-04-02T18:02:20.462317Z","steps":["trace[602321999] 'process raft request' (duration: 276.043851ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:20.46291Z","caller":"traceutil/trace.go:171","msg":"trace[507799511] transaction","detail":"{read_only:false; response_revision:8750; number_of_response:1; }","duration":"222.881216ms","start":"2026-04-02T18:02:20.24Z","end":"2026-04-02T18:02:20.462882Z","steps":["trace[507799511] 'process raft request' (duration: 222.142169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:20.972064Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113416600535026,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T18:02:21.170583Z","caller":"traceutil/trace.go:171","msg":"trace[498591389] transaction","detail":"{read_only:false; response_revision:8751; number_of_response:1; }","duration":"919.382637ms","start":"2026-04-02T18:02:20.251176Z","end":"2026-04-02T18:02:21.170558Z","steps":["trace[498591389] 'process raft request' (duration: 919.10077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:21.170806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:20.251151Z","time spent":"919.513149ms","remote":"127.0.0.1:42810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":684,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T18:02:21.170917Z","caller":"traceutil/trace.go:171","msg":"trace[2133148989] transaction","detail":"{read_only:false; response_revision:8752; number_of_response:1; }","duration":"749.99282ms","start":"2026-04-02T18:02:20.42089Z","end":"2026-04-02T18:02:21.170883Z","steps":["trace[2133148989] 'process raft request' (duration: 749.600741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:21.171117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:20.420861Z","time spent":"750.146924ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T18:02:21.22189Z","caller":"traceutil/trace.go:171","msg":"trace[1758286441] transaction","detail":"{read_only:false; response_revision:8753; number_of_response:1; }","duration":"621.077683ms","start":"2026-04-02T18:02:20.600721Z","end":"2026-04-02T18:02:21.221798Z","steps":["trace[1758286441] 'process raft request' (duration: 620.876349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:21.222097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:20.600688Z","time spent":"621.290699ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T18:02:21.222237Z","caller":"traceutil/trace.go:171","msg":"trace[343504131] transaction","detail":"{read_only:false; response_revision:8754; number_of_response:1; }","duration":"503.497047ms","start":"2026-04-02T18:02:20.718697Z","end":"2026-04-02T18:02:21.222194Z","steps":["trace[343504131] 'process raft request' (duration: 503.047606ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:21.222327Z","caller":"traceutil/trace.go:171","msg":"trace[1104501704] transaction","detail":"{read_only:false; response_revision:8755; number_of_response:1; }","duration":"503.376384ms","start":"2026-04-02T18:02:20.718911Z","end":"2026-04-02T18:02:21.222288Z","steps":["trace[1104501704] 'process raft request' (duration: 503.174989ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:21.222391Z","caller":"traceutil/trace.go:171","msg":"trace[749464166] linearizableReadLoop","detail":"{readStateIndex:9068; appliedIndex:9063; }","duration":"751.23735ms","start":"2026-04-02T18:02:20.471124Z","end":"2026-04-02T18:02:21.222361Z","steps":["trace[749464166] 'read index received' (duration: 699.194864ms)","trace[749464166] 'applied index is now lower than readState.Index' (duration: 52.041136ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T18:02:21.222474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:20.718659Z","time spent":"503.705773ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T18:02:21.222525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:20.718877Z","time spent":"503.545608ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T18:02:21.222749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.627298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-04-02T18:02:21.222822Z","caller":"traceutil/trace.go:171","msg":"trace[974494430] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8756; }","duration":"751.726811ms","start":"2026-04-02T18:02:20.471082Z","end":"2026-04-02T18:02:21.222809Z","steps":["trace[974494430] 'agreement among raft nodes before linearized reading' (duration: 751.544137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:21.22286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:20.471067Z","time spent":"751.783402ms","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":649,"request content":"key:\"/registry/configmaps/openstack/rook-config-override\" "} {"level":"warn","ts":"2026-04-02T18:02:21.237755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.892855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T18:02:21.237849Z","caller":"traceutil/trace.go:171","msg":"trace[1875517949] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8757; }","duration":"762.003337ms","start":"2026-04-02T18:02:20.475819Z","end":"2026-04-02T18:02:21.237822Z","steps":["trace[1875517949] 'agreement among raft nodes before linearized reading' (duration: 761.829553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:21.2379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:20.475811Z","time spent":"762.076549ms","remote":"127.0.0.1:55126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true "} {"level":"warn","ts":"2026-04-02T18:02:21.238071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.565424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T18:02:21.238135Z","caller":"traceutil/trace.go:171","msg":"trace[1137328625] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:8757; }","duration":"408.709747ms","start":"2026-04-02T18:02:20.829407Z","end":"2026-04-02T18:02:21.238117Z","steps":["trace[1137328625] 'agreement among raft nodes before linearized reading' (duration: 408.564424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:21.238159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.344387ms","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-04-02T18:02:21.238173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.783761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gf8g9\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-04-02T18:02:21.237758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.867112ms","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-04-02T18:02:21.238241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.764698ms","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":"warn","ts":"2026-04-02T18:02:21.238281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.618723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T18:02:21.238284Z","caller":"traceutil/trace.go:171","msg":"trace[723764354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8757; }","duration":"480.441506ms","start":"2026-04-02T18:02:20.757822Z","end":"2026-04-02T18:02:21.238263Z","steps":["trace[723764354] 'agreement among raft nodes before linearized reading' (duration: 479.75967ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:21.238307Z","caller":"traceutil/trace.go:171","msg":"trace[1666657085] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8757; }","duration":"722.8768ms","start":"2026-04-02T18:02:20.515412Z","end":"2026-04-02T18:02:21.238288Z","steps":["trace[1666657085] 'agreement among raft nodes before linearized reading' (duration: 722.780998ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:21.238323Z","caller":"traceutil/trace.go:171","msg":"trace[1416688059] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:8757; }","duration":"301.702425ms","start":"2026-04-02T18:02:20.936607Z","end":"2026-04-02T18:02:21.238309Z","steps":["trace[1416688059] 'agreement among raft nodes before linearized reading' (duration: 301.592502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:21.23831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.799991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-02T18:02:21.238359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:20.757805Z","time spent":"480.535398ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T18:02:21.238357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:20.515386Z","time spent":"722.958233ms","remote":"127.0.0.1:42956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-02T18:02:21.238362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:20.936579Z","time spent":"301.772677ms","remote":"127.0.0.1:32890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":43,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-04-02T18:02:21.238397Z","caller":"traceutil/trace.go:171","msg":"trace[1953654332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8757; }","duration":"497.960704ms","start":"2026-04-02T18:02:20.740416Z","end":"2026-04-02T18:02:21.238376Z","steps":["trace[1953654332] 'agreement among raft nodes before linearized reading' (duration: 497.670308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:21.23807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.336753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-02T18:02:21.238452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:20.740386Z","time spent":"498.055447ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T18:02:21.238413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.240138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T18:02:21.238199Z","caller":"traceutil/trace.go:171","msg":"trace[401658417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8757; }","duration":"210.436969ms","start":"2026-04-02T18:02:21.02775Z","end":"2026-04-02T18:02:21.238187Z","steps":["trace[401658417] 'agreement among raft nodes before linearized reading' (duration: 210.226644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:21.238173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:20.829375Z","time spent":"408.788129ms","remote":"127.0.0.1:54662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"info","ts":"2026-04-02T18:02:21.238255Z","caller":"traceutil/trace.go:171","msg":"trace[1399507933] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gf8g9; range_end:; response_count:1; response_revision:8757; }","duration":"472.879284ms","start":"2026-04-02T18:02:20.76536Z","end":"2026-04-02T18:02:21.238239Z","steps":["trace[1399507933] 'agreement among raft nodes before linearized reading' (duration: 472.753171ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:21.238497Z","caller":"traceutil/trace.go:171","msg":"trace[1257308204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8757; }","duration":"442.811954ms","start":"2026-04-02T18:02:20.795677Z","end":"2026-04-02T18:02:21.238489Z","steps":["trace[1257308204] 'agreement among raft nodes before linearized reading' (duration: 442.309802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:21.238785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:20.795655Z","time spent":"443.114141ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T18:02:21.238581Z","caller":"traceutil/trace.go:171","msg":"trace[1789486952] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:8757; }","duration":"492.503104ms","start":"2026-04-02T18:02:20.746027Z","end":"2026-04-02T18:02:21.23853Z","steps":["trace[1789486952] 'agreement among raft nodes before linearized reading' (duration: 492.184076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:21.239021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:20.746003Z","time spent":"492.985456ms","remote":"127.0.0.1:32782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-02T18:02:21.238722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:20.765347Z","time spent":"473.365515ms","remote":"127.0.0.1:42920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-gf8g9\" "} {"level":"info","ts":"2026-04-02T18:02:21.344581Z","caller":"traceutil/trace.go:171","msg":"trace[1325938702] transaction","detail":"{read_only:false; response_revision:8760; number_of_response:1; }","duration":"101.799127ms","start":"2026-04-02T18:02:21.242704Z","end":"2026-04-02T18:02:21.344503Z","steps":["trace[1325938702] 'process raft request' (duration: 101.546231ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:21.344614Z","caller":"traceutil/trace.go:171","msg":"trace[651254774] transaction","detail":"{read_only:false; response_revision:8762; number_of_response:1; }","duration":"100.802764ms","start":"2026-04-02T18:02:21.243775Z","end":"2026-04-02T18:02:21.344577Z","steps":["trace[651254774] 'process raft request' (duration: 100.65627ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:21.344779Z","caller":"traceutil/trace.go:171","msg":"trace[1435938395] transaction","detail":"{read_only:false; response_revision:8759; number_of_response:1; }","duration":"101.791267ms","start":"2026-04-02T18:02:21.242654Z","end":"2026-04-02T18:02:21.344445Z","steps":["trace[1435938395] 'process raft request' (duration: 101.360027ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:21.344986Z","caller":"traceutil/trace.go:171","msg":"trace[234469174] transaction","detail":"{read_only:false; response_revision:8763; number_of_response:1; }","duration":"100.725092ms","start":"2026-04-02T18:02:21.244243Z","end":"2026-04-02T18:02:21.344968Z","steps":["trace[234469174] 'process raft request' (duration: 100.249211ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:21.345071Z","caller":"traceutil/trace.go:171","msg":"trace[1958944640] transaction","detail":"{read_only:false; response_revision:8764; number_of_response:1; }","duration":"100.725892ms","start":"2026-04-02T18:02:21.244319Z","end":"2026-04-02T18:02:21.345044Z","steps":["trace[1958944640] 'process raft request' (duration: 100.513217ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:21.345022Z","caller":"traceutil/trace.go:171","msg":"trace[690254883] transaction","detail":"{read_only:false; response_revision:8761; number_of_response:1; }","duration":"101.594533ms","start":"2026-04-02T18:02:21.243158Z","end":"2026-04-02T18:02:21.344753Z","steps":["trace[690254883] 'process raft request' (duration: 101.183853ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:21.345152Z","caller":"traceutil/trace.go:171","msg":"trace[243529634] linearizableReadLoop","detail":"{readStateIndex:9073; appliedIndex:9070; }","duration":"101.977202ms","start":"2026-04-02T18:02:21.242946Z","end":"2026-04-02T18:02:21.344923Z","steps":["trace[243529634] 'read index received' (duration: 37.916108ms)","trace[243529634] 'applied index is now lower than readState.Index' (duration: 64.059664ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T18:02:21.345101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.136105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-04-02T18:02:21.345325Z","caller":"traceutil/trace.go:171","msg":"trace[1305846084] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:8764; }","duration":"102.396292ms","start":"2026-04-02T18:02:21.242909Z","end":"2026-04-02T18:02:21.345305Z","steps":["trace[1305846084] 'agreement among raft nodes before linearized reading' (duration: 102.018963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:21.346447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.31768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228778"} {"level":"info","ts":"2026-04-02T18:02:21.346499Z","caller":"traceutil/trace.go:171","msg":"trace[528391799] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8764; }","duration":"102.391823ms","start":"2026-04-02T18:02:21.244095Z","end":"2026-04-02T18:02:21.346487Z","steps":["trace[528391799] 'agreement among raft nodes before linearized reading' (duration: 101.650624ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:23.425432Z","caller":"traceutil/trace.go:171","msg":"trace[1174127364] transaction","detail":"{read_only:false; response_revision:8771; number_of_response:1; }","duration":"194.757854ms","start":"2026-04-02T18:02:23.230644Z","end":"2026-04-02T18:02:23.425402Z","steps":["trace[1174127364] 'process raft request' (duration: 194.412816ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:23.425743Z","caller":"traceutil/trace.go:171","msg":"trace[862918918] transaction","detail":"{read_only:false; response_revision:8772; number_of_response:1; }","duration":"194.939529ms","start":"2026-04-02T18:02:23.230779Z","end":"2026-04-02T18:02:23.425719Z","steps":["trace[862918918] 'process raft request' (duration: 194.324734ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:23.426223Z","caller":"traceutil/trace.go:171","msg":"trace[334660144] transaction","detail":"{read_only:false; response_revision:8770; number_of_response:1; }","duration":"196.915505ms","start":"2026-04-02T18:02:23.22929Z","end":"2026-04-02T18:02:23.426205Z","steps":["trace[334660144] 'process raft request' (duration: 159.788607ms)","trace[334660144] 'compare' (duration: 35.799487ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T18:02:23.745938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.353084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T18:02:23.746199Z","caller":"traceutil/trace.go:171","msg":"trace[893445376] transaction","detail":"{read_only:false; response_revision:8774; number_of_response:1; }","duration":"314.149622ms","start":"2026-04-02T18:02:23.432027Z","end":"2026-04-02T18:02:23.746177Z","steps":["trace[893445376] 'process raft request' (duration: 314.05277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:23.746276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:23.432004Z","time spent":"314.241745ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T18:02:23.746465Z","caller":"traceutil/trace.go:171","msg":"trace[777945080] transaction","detail":"{read_only:false; response_revision:8773; number_of_response:1; }","duration":"315.152367ms","start":"2026-04-02T18:02:23.431248Z","end":"2026-04-02T18:02:23.7464Z","steps":["trace[777945080] 'process raft request' (duration: 97.236419ms)","trace[777945080] 'compare' (duration: 217.18612ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T18:02:23.746526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:23.431229Z","time spent":"315.269089ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T18:02:23.746526Z","caller":"traceutil/trace.go:171","msg":"trace[759118792] linearizableReadLoop","detail":"{readStateIndex:9087; appliedIndex:9086; }","duration":"314.46659ms","start":"2026-04-02T18:02:23.432026Z","end":"2026-04-02T18:02:23.746493Z","steps":["trace[759118792] 'read index received' (duration: 96.4567ms)","trace[759118792] 'applied index is now lower than readState.Index' (duration: 218.00678ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T18:02:23.746694Z","caller":"traceutil/trace.go:171","msg":"trace[1929426233] transaction","detail":"{read_only:false; response_revision:8775; number_of_response:1; }","duration":"313.778134ms","start":"2026-04-02T18:02:23.432885Z","end":"2026-04-02T18:02:23.746663Z","steps":["trace[1929426233] 'process raft request' (duration: 313.248971ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:23.746876Z","caller":"traceutil/trace.go:171","msg":"trace[1115952546] transaction","detail":"{read_only:false; response_revision:8776; number_of_response:1; }","duration":"313.005784ms","start":"2026-04-02T18:02:23.433846Z","end":"2026-04-02T18:02:23.746851Z","steps":["trace[1115952546] 'process raft request' (duration: 312.720657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:23.746911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:23.432868Z","time spent":"313.920737ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T18:02:23.747006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:23.43384Z","time spent":"313.106486ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T18:02:23.747085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.044824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228778"} {"level":"info","ts":"2026-04-02T18:02:23.747146Z","caller":"traceutil/trace.go:171","msg":"trace[1826040168] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8775; }","duration":"315.133087ms","start":"2026-04-02T18:02:23.431998Z","end":"2026-04-02T18:02:23.747131Z","steps":["trace[1826040168] 'agreement among raft nodes before linearized reading' (duration: 314.581773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:02:23.747188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:02:23.431978Z","time spent":"315.201428ms","remote":"127.0.0.1:42920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":26,"response size":228801,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-04-02T18:02:31.619463Z","caller":"traceutil/trace.go:171","msg":"trace[1122943079] transaction","detail":"{read_only:false; response_revision:8839; number_of_response:1; }","duration":"161.130719ms","start":"2026-04-02T18:02:31.458299Z","end":"2026-04-02T18:02:31.61943Z","steps":["trace[1122943079] 'process raft request' (duration: 160.928074ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:31.63053Z","caller":"traceutil/trace.go:171","msg":"trace[1286042023] transaction","detail":"{read_only:false; response_revision:8840; number_of_response:1; }","duration":"171.139648ms","start":"2026-04-02T18:02:31.45935Z","end":"2026-04-02T18:02:31.63049Z","steps":["trace[1286042023] 'process raft request' (duration: 170.629565ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:31.630679Z","caller":"traceutil/trace.go:171","msg":"trace[1573458517] transaction","detail":"{read_only:false; response_revision:8841; number_of_response:1; }","duration":"171.18949ms","start":"2026-04-02T18:02:31.459454Z","end":"2026-04-02T18:02:31.630643Z","steps":["trace[1573458517] 'process raft request' (duration: 170.928794ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:02:32.920595Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023dd.wal"} {"level":"info","ts":"2026-04-02T18:03:39.112143Z","caller":"traceutil/trace.go:171","msg":"trace[476015208] linearizableReadLoop","detail":"{readStateIndex:9839; appliedIndex:9838; }","duration":"163.207258ms","start":"2026-04-02T18:03:38.948901Z","end":"2026-04-02T18:03:39.112108Z","steps":["trace[476015208] 'read index received' (duration: 137.104703ms)","trace[476015208] 'applied index is now lower than readState.Index' (duration: 26.101515ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T18:03:39.112478Z","caller":"traceutil/trace.go:171","msg":"trace[736500288] transaction","detail":"{read_only:false; response_revision:9506; number_of_response:1; }","duration":"322.996646ms","start":"2026-04-02T18:03:38.789406Z","end":"2026-04-02T18:03:39.112402Z","steps":["trace[736500288] 'process raft request' (duration: 296.516291ms)","trace[736500288] 'compare' (duration: 26.030343ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T18:03:39.112603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.684341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T18:03:39.112673Z","caller":"traceutil/trace.go:171","msg":"trace[186251730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9506; }","duration":"163.833134ms","start":"2026-04-02T18:03:38.948825Z","end":"2026-04-02T18:03:39.112658Z","steps":["trace[186251730] 'agreement among raft nodes before linearized reading' (duration: 163.67701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T18:03:39.112726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T18:03:38.789378Z","time spent":"323.232871ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T18:03:51.882103Z","caller":"traceutil/trace.go:171","msg":"trace[567130304] transaction","detail":"{read_only:false; response_revision:9605; number_of_response:1; }","duration":"102.052464ms","start":"2026-04-02T18:03:51.780022Z","end":"2026-04-02T18:03:51.882074Z","steps":["trace[567130304] 'process raft request' (duration: 101.861329ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T18:03:58.27661Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-02T18:03:58.283101Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-02T18:03:58.283226Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-02T18:06:09.307532Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8153} {"level":"info","ts":"2026-04-02T18:06:09.375511Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8153,"took":"65.196169ms","hash":3136515246,"current-db-size-bytes":58220544,"current-db-size":"58 MB","current-db-size-in-use-bytes":28147712,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-02T18:06:09.3756Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3136515246,"revision":8153,"compact-revision":6066} {"level":"info","ts":"2026-04-02T18:11:09.314711Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10499} {"level":"info","ts":"2026-04-02T18:11:09.406489Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10499,"took":"88.815783ms","hash":3478197593,"current-db-size-bytes":58220544,"current-db-size":"58 MB","current-db-size-in-use-bytes":21647360,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-02T18:11:09.406567Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3478197593,"revision":10499,"compact-revision":8153}