{"level":"warn","ts":"2026-04-09T13:21:59.072373Z","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-09T13:21:59.072448Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.132:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.132:2380","--initial-cluster=instance=https://199.19.213.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.132: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-09T13:21:59.072513Z","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-09T13:21:59.072524Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.132:2380"]} {"level":"info","ts":"2026-04-09T13:21:59.072545Z","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-09T13:21:59.07333Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.132:2379"]} {"level":"info","ts":"2026-04-09T13:21:59.073412Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.132:2380"],"listen-peer-urls":["https://199.19.213.132:2380"],"advertise-client-urls":["https://199.19.213.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.132: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-09T13:21:59.082576Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.935184ms"} {"level":"info","ts":"2026-04-09T13:21:59.15274Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"19a81b20c4b111c3","cluster-id":"42a0cab9df6b0eb"} {"level":"info","ts":"2026-04-09T13:21:59.152849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T13:21:59.152886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became follower at term 0"} {"level":"info","ts":"2026-04-09T13:21:59.152899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 19a81b20c4b111c3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T13:21:59.152905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became follower at term 1"} {"level":"info","ts":"2026-04-09T13:21:59.152947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 switched to configuration voters=(1848757474588430787)"} {"level":"warn","ts":"2026-04-09T13:21:59.191292Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T13:21:59.195735Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T13:21:59.197779Z","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-09T13:21:59.202389Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"19a81b20c4b111c3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T13:21:59.20263Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"19a81b20c4b111c3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T13:21:59.202869Z","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-09T13:21:59.20376Z","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-09T13:21:59.203787Z","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-09T13:21:59.205796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 switched to configuration voters=(1848757474588430787)"} {"level":"info","ts":"2026-04-09T13:21:59.206009Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"42a0cab9df6b0eb","local-member-id":"19a81b20c4b111c3","added-peer-id":"19a81b20c4b111c3","added-peer-peer-urls":["https://199.19.213.132:2380"]} {"level":"info","ts":"2026-04-09T13:21:59.208416Z","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-09T13:21:59.208471Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.132:2380"} {"level":"info","ts":"2026-04-09T13:21:59.208488Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.132:2380"} {"level":"info","ts":"2026-04-09T13:21:59.209339Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"19a81b20c4b111c3","initial-advertise-peer-urls":["https://199.19.213.132:2380"],"listen-peer-urls":["https://199.19.213.132:2380"],"advertise-client-urls":["https://199.19.213.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T13:21:59.209396Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T13:21:59.654175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T13:21:59.654245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T13:21:59.65428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 received MsgPreVoteResp from 19a81b20c4b111c3 at term 1"} {"level":"info","ts":"2026-04-09T13:21:59.654309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became candidate at term 2"} {"level":"info","ts":"2026-04-09T13:21:59.654346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 received MsgVoteResp from 19a81b20c4b111c3 at term 2"} {"level":"info","ts":"2026-04-09T13:21:59.654372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became leader at term 2"} {"level":"info","ts":"2026-04-09T13:21:59.654462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 19a81b20c4b111c3 elected leader 19a81b20c4b111c3 at term 2"} {"level":"info","ts":"2026-04-09T13:21:59.656037Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"19a81b20c4b111c3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.132:2379]}","request-path":"/0/members/19a81b20c4b111c3/attributes","cluster-id":"42a0cab9df6b0eb","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T13:21:59.656078Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T13:21:59.656041Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T13:21:59.656088Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T13:21:59.65636Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T13:21:59.656433Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T13:21:59.661157Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.132:2379"} {"level":"info","ts":"2026-04-09T13:21:59.661412Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"42a0cab9df6b0eb","local-member-id":"19a81b20c4b111c3","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T13:21:59.661583Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T13:21:59.661638Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T13:21:59.661833Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T13:22:01.144473Z","caller":"traceutil/trace.go:171","msg":"trace[979514692] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"102.438185ms","start":"2026-04-09T13:22:01.041985Z","end":"2026-04-09T13:22:01.144423Z","steps":["trace[979514692] 'process raft request' (duration: 102.354315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:01.14475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.98415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-09T13:22:01.144882Z","caller":"traceutil/trace.go:171","msg":"trace[1130645340] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:27; }","duration":"104.195551ms","start":"2026-04-09T13:22:01.040665Z","end":"2026-04-09T13:22:01.144861Z","steps":["trace[1130645340] 'agreement among raft nodes before linearized reading' (duration: 103.895049ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:01.144893Z","caller":"traceutil/trace.go:171","msg":"trace[1303659259] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"102.852019ms","start":"2026-04-09T13:22:01.041983Z","end":"2026-04-09T13:22:01.144835Z","steps":["trace[1303659259] 'process raft request' (duration: 102.384055ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:01.14496Z","caller":"traceutil/trace.go:171","msg":"trace[1635183734] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"102.570927ms","start":"2026-04-09T13:22:01.04236Z","end":"2026-04-09T13:22:01.14493Z","steps":["trace[1635183734] 'process raft request' (duration: 102.075583ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:01.144937Z","caller":"traceutil/trace.go:171","msg":"trace[230159264] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"102.480036ms","start":"2026-04-09T13:22:01.042331Z","end":"2026-04-09T13:22:01.144811Z","steps":["trace[230159264] 'process raft request' (duration: 102.065382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:01.145106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.018522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T13:22:01.145154Z","caller":"traceutil/trace.go:171","msg":"trace[1367620143] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:27; }","duration":"102.088282ms","start":"2026-04-09T13:22:01.043054Z","end":"2026-04-09T13:22:01.145142Z","steps":["trace[1367620143] 'agreement among raft nodes before linearized reading' (duration: 102.011331ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:01.146065Z","caller":"traceutil/trace.go:171","msg":"trace[1141628240] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"104.612176ms","start":"2026-04-09T13:22:01.041404Z","end":"2026-04-09T13:22:01.146017Z","steps":["trace[1141628240] 'process raft request' (duration: 76.652311ms)","trace[1141628240] 'compare' (duration: 26.135628ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:22:02.177755Z","caller":"traceutil/trace.go:171","msg":"trace[1128900369] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"130.389406ms","start":"2026-04-09T13:22:02.047337Z","end":"2026-04-09T13:22:02.177727Z","steps":["trace[1128900369] 'process raft request' (duration: 70.068612ms)","trace[1128900369] 'compare' (duration: 60.136032ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T13:22:02.584964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.754876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-09T13:22:02.585082Z","caller":"traceutil/trace.go:171","msg":"trace[595607665] transaction","detail":"{read_only:false; response_revision:93; number_of_response:1; }","duration":"192.281663ms","start":"2026-04-09T13:22:02.392781Z","end":"2026-04-09T13:22:02.585063Z","steps":["trace[595607665] 'process raft request' (duration: 27.879771ms)","trace[595607665] 'compare' (duration: 163.569175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T13:22:02.995638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.578821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T13:22:02.99573Z","caller":"traceutil/trace.go:171","msg":"trace[519447536] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver; range_end:; response_count:0; response_revision:95; }","duration":"309.723952ms","start":"2026-04-09T13:22:02.685984Z","end":"2026-04-09T13:22:02.995708Z","steps":["trace[519447536] 'range keys from in-memory index tree' (duration: 309.49442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:02.995775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:02.685965Z","time spent":"309.799723ms","remote":"127.0.0.1:36282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" "} {"level":"warn","ts":"2026-04-09T13:22:03.523859Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1280039833801945608,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T13:22:03.59121Z","caller":"traceutil/trace.go:171","msg":"trace[424664343] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"592.08204ms","start":"2026-04-09T13:22:02.999104Z","end":"2026-04-09T13:22:03.591186Z","steps":["trace[424664343] 'process raft request' (duration: 591.919418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:03.591338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:02.999079Z","time spent":"592.209091ms","remote":"127.0.0.1:36282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":666,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T13:22:04.496594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.208332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-09T13:22:04.496784Z","caller":"traceutil/trace.go:171","msg":"trace[1094530706] linearizableReadLoop","detail":"{readStateIndex:101; appliedIndex:99; }","duration":"1.473251886s","start":"2026-04-09T13:22:03.023479Z","end":"2026-04-09T13:22:04.496731Z","steps":["trace[1094530706] 'read index received' (duration: 567.45717ms)","trace[1094530706] 'applied index is now lower than readState.Index' (duration: 905.792916ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:22:04.496786Z","caller":"traceutil/trace.go:171","msg":"trace[149043517] transaction","detail":"{read_only:false; response_revision:97; number_of_response:1; }","duration":"1.118945972s","start":"2026-04-09T13:22:03.377804Z","end":"2026-04-09T13:22:04.49675Z","steps":["trace[149043517] 'process raft request' (duration: 496.504008ms)","trace[149043517] 'compare' (duration: 622.074061ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T13:22:04.49692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:03.377777Z","time spent":"1.119068774s","remote":"127.0.0.1:36192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T13:22:04.496956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.126532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T13:22:04.497Z","caller":"traceutil/trace.go:171","msg":"trace[657873906] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver; range_end:; response_count:0; response_revision:97; }","duration":"903.206912ms","start":"2026-04-09T13:22:03.593777Z","end":"2026-04-09T13:22:04.496984Z","steps":["trace[657873906] 'agreement among raft nodes before linearized reading' (duration: 903.094091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:04.497015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.473487548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T13:22:04.497104Z","caller":"traceutil/trace.go:171","msg":"trace[494960630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:97; }","duration":"1.473635709s","start":"2026-04-09T13:22:03.023444Z","end":"2026-04-09T13:22:04.49708Z","steps":["trace[494960630] 'agreement among raft nodes before linearized reading' (duration: 1.473399557s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:04.497149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:03.02342Z","time spent":"1.47372044s","remote":"127.0.0.1:35972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T13:22:04.49719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.063359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-09T13:22:04.49725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.204869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-09T13:22:04.497265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.194889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T13:22:04.497278Z","caller":"traceutil/trace.go:171","msg":"trace[1132370544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:97; }","duration":"439.212989ms","start":"2026-04-09T13:22:04.058037Z","end":"2026-04-09T13:22:04.49725Z","steps":["trace[1132370544] 'agreement among raft nodes before linearized reading' (duration: 439.038808ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:04.497309Z","caller":"traceutil/trace.go:171","msg":"trace[712728538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:97; }","duration":"860.290521ms","start":"2026-04-09T13:22:03.637Z","end":"2026-04-09T13:22:04.49729Z","steps":["trace[712728538] 'agreement among raft nodes before linearized reading' (duration: 860.19803ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:04.497339Z","caller":"traceutil/trace.go:171","msg":"trace[958754253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:97; }","duration":"860.27965ms","start":"2026-04-09T13:22:03.637038Z","end":"2026-04-09T13:22:04.497317Z","steps":["trace[958754253] 'agreement among raft nodes before linearized reading' (duration: 860.145129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:04.497358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:03.636982Z","time spent":"860.363271ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T13:22:04.497353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:04.057992Z","time spent":"439.344901ms","remote":"127.0.0.1:35972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T13:22:04.497374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:03.637029Z","time spent":"860.3366ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T13:22:04.497041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:03.593765Z","time spent":"903.265052ms","remote":"127.0.0.1:36282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver\" "} {"level":"info","ts":"2026-04-09T13:22:10.573325Z","caller":"traceutil/trace.go:171","msg":"trace[285810632] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"152.177816ms","start":"2026-04-09T13:22:10.421105Z","end":"2026-04-09T13:22:10.573283Z","steps":["trace[285810632] 'process raft request' (duration: 150.837185ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:10.574925Z","caller":"traceutil/trace.go:171","msg":"trace[678804021] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:359; }","duration":"124.711911ms","start":"2026-04-09T13:22:10.45019Z","end":"2026-04-09T13:22:10.574902Z","steps":["trace[678804021] 'read index received' (duration: 121.756706ms)","trace[678804021] 'applied index is now lower than readState.Index' (duration: 2.953925ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:22:10.575032Z","caller":"traceutil/trace.go:171","msg":"trace[189516636] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"145.833124ms","start":"2026-04-09T13:22:10.429179Z","end":"2026-04-09T13:22:10.575013Z","steps":["trace[189516636] 'process raft request' (duration: 145.598482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:10.5751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.893302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-09T13:22:10.575179Z","caller":"traceutil/trace.go:171","msg":"trace[1057549852] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:350; }","duration":"125.026274ms","start":"2026-04-09T13:22:10.450141Z","end":"2026-04-09T13:22:10.575167Z","steps":["trace[1057549852] 'agreement among raft nodes before linearized reading' (duration: 124.895243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:10.83805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.169208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T13:22:10.838159Z","caller":"traceutil/trace.go:171","msg":"trace[1272344664] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:367; }","duration":"182.323833ms","start":"2026-04-09T13:22:10.655816Z","end":"2026-04-09T13:22:10.83814Z","steps":["trace[1272344664] 'read index received' (duration: 74.923903ms)","trace[1272344664] 'applied index is now lower than readState.Index' (duration: 107.3985ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:22:10.838233Z","caller":"traceutil/trace.go:171","msg":"trace[1103964815] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"183.025208ms","start":"2026-04-09T13:22:10.655194Z","end":"2026-04-09T13:22:10.83822Z","steps":["trace[1103964815] 'process raft request' (duration: 75.421207ms)","trace[1103964815] 'compare' (duration: 107.041227ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T13:22:10.838493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.652685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:2205"} {"level":"info","ts":"2026-04-09T13:22:10.83858Z","caller":"traceutil/trace.go:171","msg":"trace[234413615] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:357; }","duration":"182.770976ms","start":"2026-04-09T13:22:10.65579Z","end":"2026-04-09T13:22:10.838561Z","steps":["trace[234413615] 'agreement among raft nodes before linearized reading' (duration: 182.577294ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:10.846954Z","caller":"traceutil/trace.go:171","msg":"trace[1572337202] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"190.631121ms","start":"2026-04-09T13:22:10.6563Z","end":"2026-04-09T13:22:10.846931Z","steps":["trace[1572337202] 'process raft request' (duration: 190.406779ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:10.846985Z","caller":"traceutil/trace.go:171","msg":"trace[1363686555] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"188.722125ms","start":"2026-04-09T13:22:10.658244Z","end":"2026-04-09T13:22:10.846966Z","steps":["trace[1363686555] 'process raft request' (duration: 188.580594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:10.846989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.450469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-09T13:22:10.847059Z","caller":"traceutil/trace.go:171","msg":"trace[1515353814] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:359; }","duration":"146.58194ms","start":"2026-04-09T13:22:10.700457Z","end":"2026-04-09T13:22:10.847038Z","steps":["trace[1515353814] 'agreement among raft nodes before linearized reading' (duration: 146.437928ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:30.868442Z","caller":"traceutil/trace.go:171","msg":"trace[461632604] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"180.713816ms","start":"2026-04-09T13:22:30.687703Z","end":"2026-04-09T13:22:30.868417Z","steps":["trace[461632604] 'process raft request' (duration: 180.566865ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:30.878772Z","caller":"traceutil/trace.go:171","msg":"trace[1715135652] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:523; }","duration":"153.052217ms","start":"2026-04-09T13:22:30.725698Z","end":"2026-04-09T13:22:30.878751Z","steps":["trace[1715135652] 'read index received' (duration: 142.582536ms)","trace[1715135652] 'applied index is now lower than readState.Index' (duration: 10.467951ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T13:22:30.878912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.192798ms","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-09T13:22:30.879021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.548299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:22:30.879133Z","caller":"traceutil/trace.go:171","msg":"trace[919154440] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:509; }","duration":"141.70843ms","start":"2026-04-09T13:22:30.737403Z","end":"2026-04-09T13:22:30.879112Z","steps":["trace[919154440] 'agreement among raft nodes before linearized reading' (duration: 141.525678ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:30.87904Z","caller":"traceutil/trace.go:171","msg":"trace[1138611609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:509; }","duration":"153.353889ms","start":"2026-04-09T13:22:30.725659Z","end":"2026-04-09T13:22:30.879013Z","steps":["trace[1138611609] 'agreement among raft nodes before linearized reading' (duration: 153.157228ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:31.361997Z","caller":"traceutil/trace.go:171","msg":"trace[397100855] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"108.641158ms","start":"2026-04-09T13:22:31.253332Z","end":"2026-04-09T13:22:31.361973Z","steps":["trace[397100855] 'process raft request' (duration: 59.517105ms)","trace[397100855] 'compare' (duration: 48.992192ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:22:31.363677Z","caller":"traceutil/trace.go:171","msg":"trace[1582756775] transaction","detail":"{read_only:false; number_of_response:0; response_revision:527; }","duration":"102.771678ms","start":"2026-04-09T13:22:31.260885Z","end":"2026-04-09T13:22:31.363657Z","steps":["trace[1582756775] 'process raft request' (duration: 102.260885ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:31.363738Z","caller":"traceutil/trace.go:171","msg":"trace[1910955665] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"103.06184ms","start":"2026-04-09T13:22:31.260655Z","end":"2026-04-09T13:22:31.363717Z","steps":["trace[1910955665] 'process raft request' (duration: 102.423235ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:31.657156Z","caller":"traceutil/trace.go:171","msg":"trace[617458194] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"285.456039ms","start":"2026-04-09T13:22:31.371653Z","end":"2026-04-09T13:22:31.657109Z","steps":["trace[617458194] 'process raft request' (duration: 285.237367ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:31.657271Z","caller":"traceutil/trace.go:171","msg":"trace[201827444] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"279.412609ms","start":"2026-04-09T13:22:31.377801Z","end":"2026-04-09T13:22:31.657213Z","steps":["trace[201827444] 'process raft request' (duration: 279.202757ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:31.657576Z","caller":"traceutil/trace.go:171","msg":"trace[1906843339] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"268.200802ms","start":"2026-04-09T13:22:31.389323Z","end":"2026-04-09T13:22:31.657524Z","steps":["trace[1906843339] 'process raft request' (duration: 267.89282ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:31.657677Z","caller":"traceutil/trace.go:171","msg":"trace[609028104] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"268.197052ms","start":"2026-04-09T13:22:31.389423Z","end":"2026-04-09T13:22:31.65762Z","steps":["trace[609028104] 'process raft request' (duration: 267.88277ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:31.65775Z","caller":"traceutil/trace.go:171","msg":"trace[686290237] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"256.414122ms","start":"2026-04-09T13:22:31.401317Z","end":"2026-04-09T13:22:31.657731Z","steps":["trace[686290237] 'process raft request' (duration: 256.16639ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:31.657778Z","caller":"traceutil/trace.go:171","msg":"trace[1496083590] transaction","detail":"{read_only:false; number_of_response:0; response_revision:534; }","duration":"289.519807ms","start":"2026-04-09T13:22:31.367507Z","end":"2026-04-09T13:22:31.657027Z","steps":["trace[1496083590] 'process raft request' (duration: 207.810672ms)","trace[1496083590] 'compare' (duration: 81.491504ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:22:31.658175Z","caller":"traceutil/trace.go:171","msg":"trace[1027960451] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"277.820178ms","start":"2026-04-09T13:22:31.379557Z","end":"2026-04-09T13:22:31.657377Z","steps":["trace[1027960451] 'process raft request' (duration: 277.518656ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:31.662038Z","caller":"traceutil/trace.go:171","msg":"trace[1532674350] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"199.372494ms","start":"2026-04-09T13:22:31.462656Z","end":"2026-04-09T13:22:31.662028Z","steps":["trace[1532674350] 'process raft request' (duration: 199.073672ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:31.662215Z","caller":"traceutil/trace.go:171","msg":"trace[243612938] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"191.530331ms","start":"2026-04-09T13:22:31.470668Z","end":"2026-04-09T13:22:31.662199Z","steps":["trace[243612938] 'process raft request' (duration: 191.128089ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:31.935496Z","caller":"traceutil/trace.go:171","msg":"trace[338136043] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"137.444643ms","start":"2026-04-09T13:22:31.79798Z","end":"2026-04-09T13:22:31.935425Z","steps":["trace[338136043] 'process raft request' (duration: 86.9158ms)","trace[338136043] 'compare' (duration: 50.262661ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:22:31.935614Z","caller":"traceutil/trace.go:171","msg":"trace[265931308] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"101.094596ms","start":"2026-04-09T13:22:31.834492Z","end":"2026-04-09T13:22:31.935586Z","steps":["trace[265931308] 'process raft request' (duration: 100.861805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:32.962535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.688078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-04-09T13:22:32.962284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.598452ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:22:32.964318Z","caller":"traceutil/trace.go:171","msg":"trace[169892764] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"486.368641ms","start":"2026-04-09T13:22:32.477915Z","end":"2026-04-09T13:22:32.964283Z","steps":["trace[169892764] 'process raft request' (duration: 51.86381ms)","trace[169892764] 'compare' (duration: 431.642591ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T13:22:32.964448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:32.477897Z","time spent":"486.490921ms","remote":"127.0.0.1:36016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T13:22:32.964513Z","caller":"traceutil/trace.go:171","msg":"trace[1252309636] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"417.300035ms","start":"2026-04-09T13:22:32.547153Z","end":"2026-04-09T13:22:32.964453Z","steps":["trace[1252309636] 'process raft request' (duration: 416.960893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:32.964728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:32.54713Z","time spent":"417.500486ms","remote":"127.0.0.1:36016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T13:22:32.964352Z","caller":"traceutil/trace.go:171","msg":"trace[1706385366] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"474.550951ms","start":"2026-04-09T13:22:32.489773Z","end":"2026-04-09T13:22:32.964324Z","steps":["trace[1706385366] 'process raft request' (duration: 474.160328ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:32.965117Z","caller":"traceutil/trace.go:171","msg":"trace[325166278] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:573; }","duration":"268.356391ms","start":"2026-04-09T13:22:32.696107Z","end":"2026-04-09T13:22:32.964464Z","steps":["trace[325166278] 'range keys from in-memory index tree' (duration: 265.439231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:32.965283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:32.489753Z","time spent":"475.439667ms","remote":"127.0.0.1:36016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T13:22:33.710189Z","caller":"traceutil/trace.go:171","msg":"trace[1429591921] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"100.078641ms","start":"2026-04-09T13:22:33.610085Z","end":"2026-04-09T13:22:33.710164Z","steps":["trace[1429591921] 'process raft request' (duration: 99.808749ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:39.976824Z","caller":"traceutil/trace.go:171","msg":"trace[1867326538] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"205.057885ms","start":"2026-04-09T13:22:39.771747Z","end":"2026-04-09T13:22:39.976805Z","steps":["trace[1867326538] 'read index received' (duration: 204.908354ms)","trace[1867326538] 'applied index is now lower than readState.Index' (duration: 148.621µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:22:39.976909Z","caller":"traceutil/trace.go:171","msg":"trace[12528885] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"225.90083ms","start":"2026-04-09T13:22:39.750977Z","end":"2026-04-09T13:22:39.976878Z","steps":["trace[12528885] 'process raft request' (duration: 225.696528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:39.977126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.326147ms","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-09T13:22:39.977189Z","caller":"traceutil/trace.go:171","msg":"trace[564627207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"205.478207ms","start":"2026-04-09T13:22:39.771702Z","end":"2026-04-09T13:22:39.97718Z","steps":["trace[564627207] 'agreement among raft nodes before linearized reading' (duration: 205.181786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:40.615423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.667686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:22:40.615519Z","caller":"traceutil/trace.go:171","msg":"trace[239758832] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:633; }","duration":"579.801296ms","start":"2026-04-09T13:22:40.035695Z","end":"2026-04-09T13:22:40.615497Z","steps":["trace[239758832] 'range keys from in-memory index tree' (duration: 575.251156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:40.615571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:40.035678Z","time spent":"579.867227ms","remote":"127.0.0.1:54846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-09T13:22:40.615897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.718339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:22:40.615931Z","caller":"traceutil/trace.go:171","msg":"trace[2145110267] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:633; }","duration":"217.765079ms","start":"2026-04-09T13:22:40.398158Z","end":"2026-04-09T13:22:40.615923Z","steps":["trace[2145110267] 'range keys from in-memory index tree' (duration: 217.690559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:40.616132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.26406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:22:40.616168Z","caller":"traceutil/trace.go:171","msg":"trace[296501030] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:633; }","duration":"146.310701ms","start":"2026-04-09T13:22:40.46985Z","end":"2026-04-09T13:22:40.61616Z","steps":["trace[296501030] 'range keys from in-memory index tree' (duration: 146.21356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:40.616125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.606869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T13:22:40.616166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.162984ms","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-09T13:22:40.6163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.170482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T13:22:40.616346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.455801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:22:40.616364Z","caller":"traceutil/trace.go:171","msg":"trace[1014041806] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:633; }","duration":"218.251533ms","start":"2026-04-09T13:22:40.398103Z","end":"2026-04-09T13:22:40.616354Z","steps":["trace[1014041806] 'range keys from in-memory index tree' (duration: 218.050851ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:40.616382Z","caller":"traceutil/trace.go:171","msg":"trace[1691871287] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:633; }","duration":"580.508361ms","start":"2026-04-09T13:22:40.035861Z","end":"2026-04-09T13:22:40.61637Z","steps":["trace[1691871287] 'count revisions from in-memory index tree' (duration: 576.056542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:40.616469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:40.035857Z","time spent":"580.592341ms","remote":"127.0.0.1:54846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-09T13:22:40.61665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.421734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:22:40.616733Z","caller":"traceutil/trace.go:171","msg":"trace[228507844] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:633; }","duration":"218.520144ms","start":"2026-04-09T13:22:40.398204Z","end":"2026-04-09T13:22:40.616724Z","steps":["trace[228507844] 'count revisions from in-memory index tree' (duration: 218.393384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:40.616822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.919917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:22:40.616866Z","caller":"traceutil/trace.go:171","msg":"trace[15739084] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:633; }","duration":"579.979767ms","start":"2026-04-09T13:22:40.036874Z","end":"2026-04-09T13:22:40.616854Z","steps":["trace[15739084] 'range keys from in-memory index tree' (duration: 576.521285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:40.616904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:40.036869Z","time spent":"580.025837ms","remote":"127.0.0.1:54846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-09T13:22:40.616916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.558382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T13:22:40.617108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.80841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:22:40.617155Z","caller":"traceutil/trace.go:171","msg":"trace[291204407] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:633; }","duration":"438.886891ms","start":"2026-04-09T13:22:40.178257Z","end":"2026-04-09T13:22:40.617144Z","steps":["trace[291204407] 'range keys from in-memory index tree' (duration: 438.754979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:40.617188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:40.17824Z","time spent":"438.939381ms","remote":"127.0.0.1:54848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-09T13:22:40.617365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.986131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T13:22:40.61738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.855147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:22:40.616293Z","caller":"traceutil/trace.go:171","msg":"trace[949261037] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:633; }","duration":"248.344546ms","start":"2026-04-09T13:22:40.367928Z","end":"2026-04-09T13:22:40.616272Z","steps":["trace[949261037] 'count revisions from in-memory index tree' (duration: 248.062814ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:40.617405Z","caller":"traceutil/trace.go:171","msg":"trace[1597172689] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:633; }","duration":"439.055372ms","start":"2026-04-09T13:22:40.178339Z","end":"2026-04-09T13:22:40.617395Z","steps":["trace[1597172689] 'count revisions from in-memory index tree' (duration: 438.932461ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:40.617426Z","caller":"traceutil/trace.go:171","msg":"trace[757081329] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:633; }","duration":"148.911128ms","start":"2026-04-09T13:22:40.468503Z","end":"2026-04-09T13:22:40.617414Z","steps":["trace[757081329] 'count revisions from in-memory index tree' (duration: 148.821936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:40.617439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:40.178323Z","time spent":"439.107172ms","remote":"127.0.0.1:54848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-09T13:22:40.617042Z","caller":"traceutil/trace.go:171","msg":"trace[1965372815] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:633; }","duration":"199.677123ms","start":"2026-04-09T13:22:40.417345Z","end":"2026-04-09T13:22:40.617023Z","steps":["trace[1965372815] 'count revisions from in-memory index tree' (duration: 199.467082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:40.617653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.223823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:22:40.617695Z","caller":"traceutil/trace.go:171","msg":"trace[1954644236] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:633; }","duration":"313.301323ms","start":"2026-04-09T13:22:40.304384Z","end":"2026-04-09T13:22:40.617685Z","steps":["trace[1954644236] 'range keys from in-memory index tree' (duration: 313.097892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:40.617732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:40.304363Z","time spent":"313.357384ms","remote":"127.0.0.1:54852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-09T13:22:40.617725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.798065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:22:40.617781Z","caller":"traceutil/trace.go:171","msg":"trace[1912156415] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:633; }","duration":"249.887786ms","start":"2026-04-09T13:22:40.367877Z","end":"2026-04-09T13:22:40.617765Z","steps":["trace[1912156415] 'range keys from in-memory index tree' (duration: 249.684524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:40.617768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.751913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:22:40.617847Z","caller":"traceutil/trace.go:171","msg":"trace[1342761971] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:633; }","duration":"286.848943ms","start":"2026-04-09T13:22:40.33098Z","end":"2026-04-09T13:22:40.617829Z","steps":["trace[1342761971] 'range keys from in-memory index tree' (duration: 286.700123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:40.617946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.748593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:22:40.617986Z","caller":"traceutil/trace.go:171","msg":"trace[317472526] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:633; }","duration":"286.810343ms","start":"2026-04-09T13:22:40.331166Z","end":"2026-04-09T13:22:40.617976Z","steps":["trace[317472526] 'count revisions from in-memory index tree' (duration: 286.685533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:40.618011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.579405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:22:40.618049Z","caller":"traceutil/trace.go:171","msg":"trace[61879869] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:633; }","duration":"313.654806ms","start":"2026-04-09T13:22:40.304385Z","end":"2026-04-09T13:22:40.618039Z","steps":["trace[61879869] 'count revisions from in-memory index tree' (duration: 313.446244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:40.618084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:40.304374Z","time spent":"313.701416ms","remote":"127.0.0.1:54852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-09T13:22:40.618113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.26866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:22:40.618141Z","caller":"traceutil/trace.go:171","msg":"trace[705365763] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:633; }","duration":"286.29841ms","start":"2026-04-09T13:22:40.331834Z","end":"2026-04-09T13:22:40.618132Z","steps":["trace[705365763] 'range keys from in-memory index tree' (duration: 286.20975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:40.618228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.377744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T13:22:40.618217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.456999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T13:22:40.618253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.551549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:22:40.618263Z","caller":"traceutil/trace.go:171","msg":"trace[1406348718] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:633; }","duration":"313.413634ms","start":"2026-04-09T13:22:40.304841Z","end":"2026-04-09T13:22:40.618254Z","steps":["trace[1406348718] 'range keys from in-memory index tree' (duration: 313.311994ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:40.618279Z","caller":"traceutil/trace.go:171","msg":"trace[1716611552] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:633; }","duration":"200.552789ms","start":"2026-04-09T13:22:40.417716Z","end":"2026-04-09T13:22:40.618269Z","steps":["trace[1716611552] 'range keys from in-memory index tree' (duration: 200.399758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:40.618294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:40.304777Z","time spent":"313.508595ms","remote":"127.0.0.1:54848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 "} {"level":"info","ts":"2026-04-09T13:22:40.618298Z","caller":"traceutil/trace.go:171","msg":"trace[1479127189] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:633; }","duration":"200.618089ms","start":"2026-04-09T13:22:40.417663Z","end":"2026-04-09T13:22:40.618281Z","steps":["trace[1479127189] 'range keys from in-memory index tree' (duration: 200.478839ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:40.616223Z","caller":"traceutil/trace.go:171","msg":"trace[689640907] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:633; }","duration":"147.76354ms","start":"2026-04-09T13:22:40.468442Z","end":"2026-04-09T13:22:40.616205Z","steps":["trace[689640907] 'range keys from in-memory index tree' (duration: 147.511968ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:42.440856Z","caller":"traceutil/trace.go:171","msg":"trace[657193554] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"134.640045ms","start":"2026-04-09T13:22:42.30619Z","end":"2026-04-09T13:22:42.44083Z","steps":["trace[657193554] 'process raft request' (duration: 64.398619ms)","trace[657193554] 'compare' (duration: 70.148336ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:22:43.974308Z","caller":"traceutil/trace.go:171","msg":"trace[1539350964] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"275.570033ms","start":"2026-04-09T13:22:43.698712Z","end":"2026-04-09T13:22:43.974282Z","steps":["trace[1539350964] 'process raft request' (duration: 275.09236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:44.232043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.956371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-09T13:22:44.23255Z","caller":"traceutil/trace.go:171","msg":"trace[2105461831] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:685; }","duration":"124.507054ms","start":"2026-04-09T13:22:44.10802Z","end":"2026-04-09T13:22:44.232527Z","steps":["trace[2105461831] 'range keys from in-memory index tree' (duration: 123.789689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:44.232204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.907191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-09T13:22:44.232745Z","caller":"traceutil/trace.go:171","msg":"trace[358317360] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:685; }","duration":"140.477905ms","start":"2026-04-09T13:22:44.092231Z","end":"2026-04-09T13:22:44.232709Z","steps":["trace[358317360] 'range keys from in-memory index tree' (duration: 139.772641ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:22:44.493189Z","caller":"traceutil/trace.go:171","msg":"trace[989320250] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"201.313788ms","start":"2026-04-09T13:22:44.291847Z","end":"2026-04-09T13:22:44.493161Z","steps":["trace[989320250] 'read index received' (duration: 201.091937ms)","trace[989320250] 'applied index is now lower than readState.Index' (duration: 220.171µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:22:44.493312Z","caller":"traceutil/trace.go:171","msg":"trace[506279824] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"248.854277ms","start":"2026-04-09T13:22:44.244447Z","end":"2026-04-09T13:22:44.493301Z","steps":["trace[506279824] 'process raft request' (duration: 248.516335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:44.493538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.69368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-04-09T13:22:44.49359Z","caller":"traceutil/trace.go:171","msg":"trace[1466121106] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:688; }","duration":"201.813232ms","start":"2026-04-09T13:22:44.291762Z","end":"2026-04-09T13:22:44.493576Z","steps":["trace[1466121106] 'agreement among raft nodes before linearized reading' (duration: 201.674491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:22:55.954555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:22:55.529758Z","time spent":"424.79217ms","remote":"127.0.0.1:36002","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-09T13:23:24.491631Z","caller":"traceutil/trace.go:171","msg":"trace[619957596] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"182.141955ms","start":"2026-04-09T13:23:24.309455Z","end":"2026-04-09T13:23:24.491597Z","steps":["trace[619957596] 'process raft request' (duration: 181.925126ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:23:24.682677Z","caller":"traceutil/trace.go:171","msg":"trace[1235450430] linearizableReadLoop","detail":"{readStateIndex:1224; appliedIndex:1222; }","duration":"304.2633ms","start":"2026-04-09T13:23:24.378381Z","end":"2026-04-09T13:23:24.682644Z","steps":["trace[1235450430] 'read index received' (duration: 112.962559ms)","trace[1235450430] 'applied index is now lower than readState.Index' (duration: 191.299131ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:23:24.682783Z","caller":"traceutil/trace.go:171","msg":"trace[658960823] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"371.77373ms","start":"2026-04-09T13:23:24.310969Z","end":"2026-04-09T13:23:24.682742Z","steps":["trace[658960823] 'process raft request' (duration: 369.074236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:23:24.682907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.503109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-09T13:23:24.682994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:23:24.310952Z","time spent":"371.924997ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T13:23:24.683033Z","caller":"traceutil/trace.go:171","msg":"trace[840102269] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1189; }","duration":"304.691557ms","start":"2026-04-09T13:23:24.378307Z","end":"2026-04-09T13:23:24.682999Z","steps":["trace[840102269] 'agreement among raft nodes before linearized reading' (duration: 304.49977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:23:24.683099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:23:24.378276Z","time spent":"304.807932ms","remote":"127.0.0.1:36310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":29,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-09T13:23:24.687783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.363637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:23:24.687849Z","caller":"traceutil/trace.go:171","msg":"trace[928624212] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1190; }","duration":"154.434219ms","start":"2026-04-09T13:23:24.533397Z","end":"2026-04-09T13:23:24.687831Z","steps":["trace[928624212] 'agreement among raft nodes before linearized reading' (duration: 154.310284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:23:24.688032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.50521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-09T13:23:24.688204Z","caller":"traceutil/trace.go:171","msg":"trace[999372587] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1190; }","duration":"168.77099ms","start":"2026-04-09T13:23:24.519408Z","end":"2026-04-09T13:23:24.688179Z","steps":["trace[999372587] 'agreement among raft nodes before linearized reading' (duration: 168.281491ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:23:45.668753Z","caller":"traceutil/trace.go:171","msg":"trace[395211786] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1388; }","duration":"137.228053ms","start":"2026-04-09T13:23:45.531258Z","end":"2026-04-09T13:23:45.668487Z","steps":["trace[395211786] 'read index received' (duration: 40.706941ms)","trace[395211786] 'applied index is now lower than readState.Index' (duration: 96.518922ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:23:45.669068Z","caller":"traceutil/trace.go:171","msg":"trace[1653763421] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"162.497202ms","start":"2026-04-09T13:23:45.506543Z","end":"2026-04-09T13:23:45.66904Z","steps":["trace[1653763421] 'process raft request' (duration: 65.505154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:23:45.669859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.584135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-09T13:23:45.669913Z","caller":"traceutil/trace.go:171","msg":"trace[426623316] range","detail":"{range_begin:/registry/masterleases/199.19.213.132; range_end:; response_count:1; response_revision:1348; }","duration":"138.683458ms","start":"2026-04-09T13:23:45.531214Z","end":"2026-04-09T13:23:45.669898Z","steps":["trace[426623316] 'agreement among raft nodes before linearized reading' (duration: 138.460471ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:23:46.46909Z","caller":"traceutil/trace.go:171","msg":"trace[436554186] linearizableReadLoop","detail":"{readStateIndex:1460; appliedIndex:1459; }","duration":"294.121126ms","start":"2026-04-09T13:23:46.174937Z","end":"2026-04-09T13:23:46.469058Z","steps":["trace[436554186] 'read index received' (duration: 293.504357ms)","trace[436554186] 'applied index is now lower than readState.Index' (duration: 615.339µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:23:46.469169Z","caller":"traceutil/trace.go:171","msg":"trace[1029205469] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"477.871247ms","start":"2026-04-09T13:23:45.991255Z","end":"2026-04-09T13:23:46.469127Z","steps":["trace[1029205469] 'process raft request' (duration: 477.347781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:23:46.469496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.493348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" ","response":"range_response_count:1 size:1020"} {"level":"info","ts":"2026-04-09T13:23:46.469559Z","caller":"traceutil/trace.go:171","msg":"trace[1479001702] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx; range_end:; response_count:1; response_revision:1417; }","duration":"294.671883ms","start":"2026-04-09T13:23:46.174873Z","end":"2026-04-09T13:23:46.469545Z","steps":["trace[1479001702] 'agreement among raft nodes before linearized reading' (duration: 294.379114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:23:46.469513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:23:45.991235Z","time spent":"478.126104ms","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105443,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T13:23:46.469704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.085065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" ","response":"range_response_count:1 size:1041"} {"level":"info","ts":"2026-04-09T13:23:46.469825Z","caller":"traceutil/trace.go:171","msg":"trace[1219648044] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend; range_end:; response_count:1; response_revision:1417; }","duration":"294.21011ms","start":"2026-04-09T13:23:46.175576Z","end":"2026-04-09T13:23:46.469786Z","steps":["trace[1219648044] 'agreement among raft nodes before linearized reading' (duration: 293.845318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:23:46.469901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.672682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:23:46.469975Z","caller":"traceutil/trace.go:171","msg":"trace[2011027967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1417; }","duration":"225.771935ms","start":"2026-04-09T13:23:46.244184Z","end":"2026-04-09T13:23:46.469956Z","steps":["trace[2011027967] 'agreement among raft nodes before linearized reading' (duration: 225.642661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:23:48.249291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.452370944s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T13:23:48.249439Z","caller":"traceutil/trace.go:171","msg":"trace[675916784] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"1.454378295s","start":"2026-04-09T13:23:46.795025Z","end":"2026-04-09T13:23:48.249403Z","steps":["trace[675916784] 'compare' (duration: 1.450978832s)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:23:48.249552Z","caller":"traceutil/trace.go:171","msg":"trace[238665519] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"384.940273ms","start":"2026-04-09T13:23:47.864586Z","end":"2026-04-09T13:23:48.249526Z","steps":["trace[238665519] 'process raft request' (duration: 384.82623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:23:48.249622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:23:47.864548Z","time spent":"385.046857ms","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2381,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T13:23:48.249713Z","caller":"traceutil/trace.go:171","msg":"trace[1498429222] linearizableReadLoop","detail":"{readStateIndex:1467; appliedIndex:1466; }","duration":"1.449917888s","start":"2026-04-09T13:23:46.799768Z","end":"2026-04-09T13:23:48.249685Z","steps":["trace[1498429222] 'read index received' (duration: 29.441µs)","trace[1498429222] 'applied index is now lower than readState.Index' (duration: 1.449886977s)"],"step_count":2} {"level":"warn","ts":"2026-04-09T13:23:48.249736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:23:46.794997Z","time spent":"1.454620943s","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T13:23:48.249943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.450157825s","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-09T13:23:48.249971Z","caller":"traceutil/trace.go:171","msg":"trace[741117357] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1425; }","duration":"1.450231377s","start":"2026-04-09T13:23:46.799729Z","end":"2026-04-09T13:23:48.249961Z","steps":["trace[741117357] 'agreement among raft nodes before linearized reading' (duration: 1.450076183s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:23:48.249997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:23:46.799717Z","time spent":"1.450273598s","remote":"127.0.0.1:36204","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-09T13:23:48.25336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.006722ms","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-09T13:23:48.253461Z","caller":"traceutil/trace.go:171","msg":"trace[324173381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1427; }","duration":"433.120926ms","start":"2026-04-09T13:23:47.82033Z","end":"2026-04-09T13:23:48.253451Z","steps":["trace[324173381] 'agreement among raft nodes before linearized reading' (duration: 432.955231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:23:48.253494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:23:47.820279Z","time spent":"433.207498ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T13:23:48.253649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.732303ms","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-09T13:23:48.254042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.666743ms","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-09T13:23:48.254078Z","caller":"traceutil/trace.go:171","msg":"trace[1116976759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1428; }","duration":"433.713374ms","start":"2026-04-09T13:23:47.820355Z","end":"2026-04-09T13:23:48.254068Z","steps":["trace[1116976759] 'agreement among raft nodes before linearized reading' (duration: 433.553459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:23:48.254098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:23:47.820352Z","time spent":"433.741954ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T13:23:48.254219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.545887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-09T13:23:48.254243Z","caller":"traceutil/trace.go:171","msg":"trace[329436869] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1428; }","duration":"431.61288ms","start":"2026-04-09T13:23:47.822625Z","end":"2026-04-09T13:23:48.254237Z","steps":["trace[329436869] 'agreement among raft nodes before linearized reading' (duration: 431.549648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:23:48.254261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:23:47.822599Z","time spent":"431.657791ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T13:23:48.25406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.325644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:23:48.254539Z","caller":"traceutil/trace.go:171","msg":"trace[1014574623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1428; }","duration":"386.837679ms","start":"2026-04-09T13:23:47.867685Z","end":"2026-04-09T13:23:48.254522Z","steps":["trace[1014574623] 'agreement among raft nodes before linearized reading' (duration: 385.693455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:23:48.254692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:23:47.86768Z","time spent":"386.994875ms","remote":"127.0.0.1:35972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T13:23:48.254989Z","caller":"traceutil/trace.go:171","msg":"trace[982210521] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1427; }","duration":"432.053182ms","start":"2026-04-09T13:23:47.822898Z","end":"2026-04-09T13:23:48.254951Z","steps":["trace[982210521] 'agreement among raft nodes before linearized reading' (duration: 430.715542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:23:48.255071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:23:47.822891Z","time spent":"432.155986ms","remote":"127.0.0.1:36038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":21,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-04-09T13:23:48.254494Z","caller":"traceutil/trace.go:171","msg":"trace[589775354] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"288.395489ms","start":"2026-04-09T13:23:47.966087Z","end":"2026-04-09T13:23:48.254483Z","steps":["trace[589775354] 'process raft request' (duration: 287.141261ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:23:48.255548Z","caller":"traceutil/trace.go:171","msg":"trace[1947294196] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"385.914642ms","start":"2026-04-09T13:23:47.867382Z","end":"2026-04-09T13:23:48.253297Z","steps":["trace[1947294196] 'process raft request' (duration: 385.306394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:23:48.257058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:23:47.86737Z","time spent":"389.236922ms","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105443,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T13:23:49.149262Z","caller":"traceutil/trace.go:171","msg":"trace[1789586095] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"110.848413ms","start":"2026-04-09T13:23:49.038391Z","end":"2026-04-09T13:23:49.149239Z","steps":["trace[1789586095] 'process raft request' (duration: 110.699988ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:10.896085Z","caller":"traceutil/trace.go:171","msg":"trace[1134945905] transaction","detail":"{read_only:false; response_revision:1770; number_of_response:1; }","duration":"745.145064ms","start":"2026-04-09T13:24:10.150872Z","end":"2026-04-09T13:24:10.896017Z","steps":["trace[1134945905] 'process raft request' (duration: 743.36679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:10.896332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:10.150851Z","time spent":"745.36455ms","remote":"127.0.0.1:36104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7351,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T13:24:10.899887Z","caller":"traceutil/trace.go:171","msg":"trace[689177054] transaction","detail":"{read_only:false; response_revision:1772; number_of_response:1; }","duration":"439.364368ms","start":"2026-04-09T13:24:10.4605Z","end":"2026-04-09T13:24:10.899865Z","steps":["trace[689177054] 'process raft request' (duration: 439.181233ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:10.899818Z","caller":"traceutil/trace.go:171","msg":"trace[771698853] transaction","detail":"{read_only:false; response_revision:1771; number_of_response:1; }","duration":"743.242927ms","start":"2026-04-09T13:24:10.156527Z","end":"2026-04-09T13:24:10.89977Z","steps":["trace[771698853] 'process raft request' (duration: 743.030832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:10.900079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:10.460484Z","time spent":"439.471071ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T13:24:10.900129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:10.156513Z","time spent":"743.577005ms","remote":"127.0.0.1:36032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":709,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T13:24:10.900505Z","caller":"traceutil/trace.go:171","msg":"trace[2103898097] transaction","detail":"{read_only:false; response_revision:1774; number_of_response:1; }","duration":"383.666385ms","start":"2026-04-09T13:24:10.516778Z","end":"2026-04-09T13:24:10.900445Z","steps":["trace[2103898097] 'process raft request' (duration: 382.999619ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:10.900768Z","caller":"traceutil/trace.go:171","msg":"trace[223186959] transaction","detail":"{read_only:false; response_revision:1773; number_of_response:1; }","duration":"438.970338ms","start":"2026-04-09T13:24:10.46143Z","end":"2026-04-09T13:24:10.900401Z","steps":["trace[223186959] 'process raft request' (duration: 438.308332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:10.900938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:10.516745Z","time spent":"383.892291ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T13:24:10.901078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:10.461423Z","time spent":"439.448391ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T13:24:10.90143Z","caller":"traceutil/trace.go:171","msg":"trace[2040040890] linearizableReadLoop","detail":"{readStateIndex:1839; appliedIndex:1837; }","duration":"460.792536ms","start":"2026-04-09T13:24:10.440625Z","end":"2026-04-09T13:24:10.901418Z","steps":["trace[2040040890] 'read index received' (duration: 453.609399ms)","trace[2040040890] 'applied index is now lower than readState.Index' (duration: 7.182487ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T13:24:10.901693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.063403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-09T13:24:10.90174Z","caller":"traceutil/trace.go:171","msg":"trace[393423476] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1774; }","duration":"461.150295ms","start":"2026-04-09T13:24:10.440581Z","end":"2026-04-09T13:24:10.901731Z","steps":["trace[393423476] 'agreement among raft nodes before linearized reading' (duration: 460.987241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:10.90189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.156495ms","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-09T13:24:10.90195Z","caller":"traceutil/trace.go:171","msg":"trace[1413658574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1774; }","duration":"461.258318ms","start":"2026-04-09T13:24:10.440676Z","end":"2026-04-09T13:24:10.901934Z","steps":["trace[1413658574] 'agreement among raft nodes before linearized reading' (duration: 461.043913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:10.902021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:10.440669Z","time spent":"461.34219ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T13:24:10.902325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:10.440559Z","time spent":"461.199606ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T13:24:10.902585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.72877ms","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-09T13:24:10.902627Z","caller":"traceutil/trace.go:171","msg":"trace[1631260920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1774; }","duration":"461.784371ms","start":"2026-04-09T13:24:10.44083Z","end":"2026-04-09T13:24:10.902615Z","steps":["trace[1631260920] 'agreement among raft nodes before linearized reading' (duration: 461.675458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:10.902666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:10.440804Z","time spent":"461.852003ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T13:24:10.902818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.752246ms","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-09T13:24:10.902977Z","caller":"traceutil/trace.go:171","msg":"trace[928770248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1774; }","duration":"443.968762ms","start":"2026-04-09T13:24:10.458982Z","end":"2026-04-09T13:24:10.902951Z","steps":["trace[928770248] 'agreement among raft nodes before linearized reading' (duration: 443.518251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:10.903068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:10.458976Z","time spent":"444.064524ms","remote":"127.0.0.1:36204","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-09T13:24:10.903798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.427431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:24:10.903831Z","caller":"traceutil/trace.go:171","msg":"trace[641397015] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1774; }","duration":"437.471391ms","start":"2026-04-09T13:24:10.46635Z","end":"2026-04-09T13:24:10.903822Z","steps":["trace[641397015] 'agreement among raft nodes before linearized reading' (duration: 437.41962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:10.903853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:10.466346Z","time spent":"437.502052ms","remote":"127.0.0.1:54946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-04-09T13:24:10.904186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.400327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T13:24:10.904231Z","caller":"traceutil/trace.go:171","msg":"trace[1539066147] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1774; }","duration":"105.509ms","start":"2026-04-09T13:24:10.798714Z","end":"2026-04-09T13:24:10.904223Z","steps":["trace[1539066147] 'agreement among raft nodes before linearized reading' (duration: 105.434528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:10.905891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.887023ms","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-09T13:24:10.905945Z","caller":"traceutil/trace.go:171","msg":"trace[464945050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1774; }","duration":"142.43234ms","start":"2026-04-09T13:24:10.763497Z","end":"2026-04-09T13:24:10.905929Z","steps":["trace[464945050] 'agreement among raft nodes before linearized reading' (duration: 138.246167ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:19.053798Z","caller":"traceutil/trace.go:171","msg":"trace[1273658873] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"117.247791ms","start":"2026-04-09T13:24:18.936517Z","end":"2026-04-09T13:24:19.053764Z","steps":["trace[1273658873] 'process raft request' (duration: 116.996305ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:19.071507Z","caller":"traceutil/trace.go:171","msg":"trace[967406325] linearizableReadLoop","detail":"{readStateIndex:1959; appliedIndex:1956; }","duration":"108.385883ms","start":"2026-04-09T13:24:18.963098Z","end":"2026-04-09T13:24:19.071484Z","steps":["trace[967406325] 'read index received' (duration: 90.510905ms)","trace[967406325] 'applied index is now lower than readState.Index' (duration: 17.873348ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:24:19.071563Z","caller":"traceutil/trace.go:171","msg":"trace[2055846439] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"131.263ms","start":"2026-04-09T13:24:18.940259Z","end":"2026-04-09T13:24:19.071522Z","steps":["trace[2055846439] 'process raft request' (duration: 131.029525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:19.071745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.188878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-09T13:24:19.071628Z","caller":"traceutil/trace.go:171","msg":"trace[184279687] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"129.895838ms","start":"2026-04-09T13:24:18.941668Z","end":"2026-04-09T13:24:19.071563Z","steps":["trace[184279687] 'process raft request' (duration: 129.772285ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:19.071793Z","caller":"traceutil/trace.go:171","msg":"trace[1916832262] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1889; }","duration":"108.296891ms","start":"2026-04-09T13:24:18.963484Z","end":"2026-04-09T13:24:19.071781Z","steps":["trace[1916832262] 'agreement among raft nodes before linearized reading' (duration: 108.117417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:19.071848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.196649ms","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-09T13:24:19.071848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.679388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-09T13:24:19.071838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.69756ms","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-09T13:24:19.071921Z","caller":"traceutil/trace.go:171","msg":"trace[883242349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1889; }","duration":"104.79476ms","start":"2026-04-09T13:24:18.967107Z","end":"2026-04-09T13:24:19.071902Z","steps":["trace[883242349] 'agreement among raft nodes before linearized reading' (duration: 104.547374ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:19.071961Z","caller":"traceutil/trace.go:171","msg":"trace[1948299552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1889; }","duration":"108.878065ms","start":"2026-04-09T13:24:18.963059Z","end":"2026-04-09T13:24:19.071937Z","steps":["trace[1948299552] 'agreement among raft nodes before linearized reading' (duration: 108.547827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:19.07197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.463616ms","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-09T13:24:19.071892Z","caller":"traceutil/trace.go:171","msg":"trace[847423021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1889; }","duration":"105.283041ms","start":"2026-04-09T13:24:18.966594Z","end":"2026-04-09T13:24:19.071877Z","steps":["trace[847423021] 'agreement among raft nodes before linearized reading' (duration: 105.149558ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:19.072075Z","caller":"traceutil/trace.go:171","msg":"trace[1958775386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1889; }","duration":"105.604298ms","start":"2026-04-09T13:24:18.966448Z","end":"2026-04-09T13:24:19.072053Z","steps":["trace[1958775386] 'agreement among raft nodes before linearized reading' (duration: 105.333022ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:19.446688Z","caller":"traceutil/trace.go:171","msg":"trace[1950556982] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1960; }","duration":"254.098425ms","start":"2026-04-09T13:24:19.192557Z","end":"2026-04-09T13:24:19.446656Z","steps":["trace[1950556982] 'read index received' (duration: 235.025519ms)","trace[1950556982] 'applied index is now lower than readState.Index' (duration: 19.071696ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:24:19.446846Z","caller":"traceutil/trace.go:171","msg":"trace[2017299277] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"367.551577ms","start":"2026-04-09T13:24:19.079272Z","end":"2026-04-09T13:24:19.446823Z","steps":["trace[2017299277] 'process raft request' (duration: 367.290541ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:19.447018Z","caller":"traceutil/trace.go:171","msg":"trace[1347535198] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"367.26736ms","start":"2026-04-09T13:24:19.079712Z","end":"2026-04-09T13:24:19.446979Z","steps":["trace[1347535198] 'process raft request' (duration: 366.892302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:19.447001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:19.079262Z","time spent":"367.64081ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T13:24:19.44711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:19.0797Z","time spent":"367.361713ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T13:24:19.447067Z","caller":"traceutil/trace.go:171","msg":"trace[1269553123] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"368.883798ms","start":"2026-04-09T13:24:19.078126Z","end":"2026-04-09T13:24:19.44701Z","steps":["trace[1269553123] 'process raft request' (duration: 349.465714ms)","trace[1269553123] 'compare' (duration: 18.386901ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:24:19.447151Z","caller":"traceutil/trace.go:171","msg":"trace[1131370701] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"368.469099ms","start":"2026-04-09T13:24:19.078655Z","end":"2026-04-09T13:24:19.447124Z","steps":["trace[1131370701] 'process raft request' (duration: 367.836124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:19.447197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.614358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c8321c54-af6b-488d-99ca-013e7c16aa24\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-09T13:24:19.447256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:19.078627Z","time spent":"368.57354ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T13:24:19.44728Z","caller":"traceutil/trace.go:171","msg":"trace[608420766] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c8321c54-af6b-488d-99ca-013e7c16aa24; range_end:; response_count:1; response_revision:1894; }","duration":"254.71051ms","start":"2026-04-09T13:24:19.192548Z","end":"2026-04-09T13:24:19.447259Z","steps":["trace[608420766] 'agreement among raft nodes before linearized reading' (duration: 254.441064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:19.447327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:19.078095Z","time spent":"369.082832ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T13:24:19.447499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.579343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:24:19.447541Z","caller":"traceutil/trace.go:171","msg":"trace[219508224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1894; }","duration":"200.619164ms","start":"2026-04-09T13:24:19.24691Z","end":"2026-04-09T13:24:19.447529Z","steps":["trace[219508224] 'agreement among raft nodes before linearized reading' (duration: 200.535352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:19.447675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.494744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c8321c54-af6b-488d-99ca-013e7c16aa24\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-09T13:24:19.447713Z","caller":"traceutil/trace.go:171","msg":"trace[331906378] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c8321c54-af6b-488d-99ca-013e7c16aa24; range_end:; response_count:1; response_revision:1894; }","duration":"138.563476ms","start":"2026-04-09T13:24:19.309138Z","end":"2026-04-09T13:24:19.447701Z","steps":["trace[331906378] 'agreement among raft nodes before linearized reading' (duration: 138.426003ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:33.128654Z","caller":"traceutil/trace.go:171","msg":"trace[1660623125] linearizableReadLoop","detail":"{readStateIndex:2068; appliedIndex:2067; }","duration":"196.757146ms","start":"2026-04-09T13:24:32.931873Z","end":"2026-04-09T13:24:33.12863Z","steps":["trace[1660623125] 'read index received' (duration: 196.635113ms)","trace[1660623125] 'applied index is now lower than readState.Index' (duration: 120.643µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:24:33.128873Z","caller":"traceutil/trace.go:171","msg":"trace[1970599651] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"198.86159ms","start":"2026-04-09T13:24:32.929972Z","end":"2026-04-09T13:24:33.128833Z","steps":["trace[1970599651] 'process raft request' (duration: 198.479402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:33.128994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.064733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T13:24:33.129046Z","caller":"traceutil/trace.go:171","msg":"trace[832850142] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1995; }","duration":"197.179295ms","start":"2026-04-09T13:24:32.931857Z","end":"2026-04-09T13:24:33.129037Z","steps":["trace[832850142] 'agreement among raft nodes before linearized reading' (duration: 196.95127ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:33.130277Z","caller":"traceutil/trace.go:171","msg":"trace[1396609668] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"147.420766ms","start":"2026-04-09T13:24:32.982839Z","end":"2026-04-09T13:24:33.13026Z","steps":["trace[1396609668] 'process raft request' (duration: 146.883135ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:48.329583Z","caller":"traceutil/trace.go:171","msg":"trace[2108810800] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"175.707753ms","start":"2026-04-09T13:24:48.153806Z","end":"2026-04-09T13:24:48.329514Z","steps":["trace[2108810800] 'process raft request' (duration: 175.381917ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:53.815336Z","caller":"traceutil/trace.go:171","msg":"trace[2076111913] linearizableReadLoop","detail":"{readStateIndex:2206; appliedIndex:2205; }","duration":"118.347284ms","start":"2026-04-09T13:24:53.696955Z","end":"2026-04-09T13:24:53.815302Z","steps":["trace[2076111913] 'read index received' (duration: 118.039559ms)","trace[2076111913] 'applied index is now lower than readState.Index' (duration: 306.115µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:24:53.815416Z","caller":"traceutil/trace.go:171","msg":"trace[1091302166] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"118.69882ms","start":"2026-04-09T13:24:53.696664Z","end":"2026-04-09T13:24:53.815363Z","steps":["trace[1091302166] 'process raft request' (duration: 118.441376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:53.815659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.6779ms","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-09T13:24:53.815708Z","caller":"traceutil/trace.go:171","msg":"trace[228389167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2129; }","duration":"118.812122ms","start":"2026-04-09T13:24:53.696884Z","end":"2026-04-09T13:24:53.815696Z","steps":["trace[228389167] 'agreement among raft nodes before linearized reading' (duration: 118.581158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:53.816049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.714325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-09T13:24:53.816325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.022117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-09T13:24:53.81637Z","caller":"traceutil/trace.go:171","msg":"trace[1865053737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2129; }","duration":"117.05312ms","start":"2026-04-09T13:24:53.699262Z","end":"2026-04-09T13:24:53.816316Z","steps":["trace[1865053737] 'agreement among raft nodes before linearized reading' (duration: 116.302957ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:53.816881Z","caller":"traceutil/trace.go:171","msg":"trace[675965387] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2129; }","duration":"114.605797ms","start":"2026-04-09T13:24:53.70225Z","end":"2026-04-09T13:24:53.816856Z","steps":["trace[675965387] 'agreement among raft nodes before linearized reading' (duration: 113.717661ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:54.045599Z","caller":"traceutil/trace.go:171","msg":"trace[540216346] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"222.00238ms","start":"2026-04-09T13:24:53.823558Z","end":"2026-04-09T13:24:54.04556Z","steps":["trace[540216346] 'process raft request' (duration: 221.914308ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:54.046232Z","caller":"traceutil/trace.go:171","msg":"trace[1035521301] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"222.885316ms","start":"2026-04-09T13:24:53.823322Z","end":"2026-04-09T13:24:54.046207Z","steps":["trace[1035521301] 'process raft request' (duration: 151.288396ms)","trace[1035521301] 'compare' (duration: 70.650583ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:24:54.046366Z","caller":"traceutil/trace.go:171","msg":"trace[2121590081] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"222.794714ms","start":"2026-04-09T13:24:53.823414Z","end":"2026-04-09T13:24:54.046208Z","steps":["trace[2121590081] 'process raft request' (duration: 222.01539ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:55.689951Z","caller":"traceutil/trace.go:171","msg":"trace[541659740] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"468.304157ms","start":"2026-04-09T13:24:55.221617Z","end":"2026-04-09T13:24:55.689921Z","steps":["trace[541659740] 'process raft request' (duration: 386.73254ms)","trace[541659740] 'compare' (duration: 81.369103ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T13:24:55.690136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:55.221568Z","time spent":"468.47224ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T13:24:55.690409Z","caller":"traceutil/trace.go:171","msg":"trace[763316859] linearizableReadLoop","detail":"{readStateIndex:2214; appliedIndex:2211; }","duration":"456.789712ms","start":"2026-04-09T13:24:55.233607Z","end":"2026-04-09T13:24:55.690397Z","steps":["trace[763316859] 'read index received' (duration: 22.378067ms)","trace[763316859] 'applied index is now lower than readState.Index' (duration: 434.410485ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:24:55.690513Z","caller":"traceutil/trace.go:171","msg":"trace[851303825] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"461.19293ms","start":"2026-04-09T13:24:55.22931Z","end":"2026-04-09T13:24:55.690503Z","steps":["trace[851303825] 'process raft request' (duration: 460.566759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:55.690575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:55.229286Z","time spent":"461.254482ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T13:24:55.690681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.079587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:24:55.690718Z","caller":"traceutil/trace.go:171","msg":"trace[1159003696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2137; }","duration":"457.140089ms","start":"2026-04-09T13:24:55.233569Z","end":"2026-04-09T13:24:55.690709Z","steps":["trace[1159003696] 'agreement among raft nodes before linearized reading' (duration: 457.082688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:55.690748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:55.233559Z","time spent":"457.183519ms","remote":"127.0.0.1:35972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T13:24:55.690945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.583576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:24:55.690984Z","caller":"traceutil/trace.go:171","msg":"trace[301630357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2137; }","duration":"444.668308ms","start":"2026-04-09T13:24:55.246304Z","end":"2026-04-09T13:24:55.690972Z","steps":["trace[301630357] 'agreement among raft nodes before linearized reading' (duration: 444.599436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:55.691013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:55.246274Z","time spent":"444.733508ms","remote":"127.0.0.1:35978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T13:24:55.691607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.637404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-09T13:24:55.691647Z","caller":"traceutil/trace.go:171","msg":"trace[1022579868] range","detail":"{range_begin:/registry/masterleases/199.19.213.132; range_end:; response_count:1; response_revision:2137; }","duration":"154.717144ms","start":"2026-04-09T13:24:55.536922Z","end":"2026-04-09T13:24:55.691639Z","steps":["trace[1022579868] 'agreement among raft nodes before linearized reading' (duration: 154.417689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:55.691651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.301714ms","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-09T13:24:55.691741Z","caller":"traceutil/trace.go:171","msg":"trace[1781979698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2137; }","duration":"407.662981ms","start":"2026-04-09T13:24:55.284056Z","end":"2026-04-09T13:24:55.691719Z","steps":["trace[1781979698] 'agreement among raft nodes before linearized reading' (duration: 407.127031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:55.691792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:55.284034Z","time spent":"407.748762ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T13:24:56.00517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.48945ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-04-09T13:24:56.005533Z","caller":"traceutil/trace.go:171","msg":"trace[1593240410] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"162.31797ms","start":"2026-04-09T13:24:55.84319Z","end":"2026-04-09T13:24:56.005508Z","steps":["trace[1593240410] 'process raft request' (duration: 162.275529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:56.005626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:55.692669Z","time spent":"312.937342ms","remote":"127.0.0.1:36002","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-09T13:24:56.005717Z","caller":"traceutil/trace.go:171","msg":"trace[351286590] linearizableReadLoop","detail":"{readStateIndex:2216; appliedIndex:2214; }","duration":"181.928718ms","start":"2026-04-09T13:24:55.82375Z","end":"2026-04-09T13:24:56.005679Z","steps":["trace[351286590] 'read index received' (duration: 154.352609ms)","trace[351286590] 'applied index is now lower than readState.Index' (duration: 27.572769ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:24:56.005794Z","caller":"traceutil/trace.go:171","msg":"trace[1324136542] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"170.435334ms","start":"2026-04-09T13:24:55.835346Z","end":"2026-04-09T13:24:56.005782Z","steps":["trace[1324136542] 'process raft request' (duration: 170.080537ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:56.005797Z","caller":"traceutil/trace.go:171","msg":"trace[437882643] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"308.069675ms","start":"2026-04-09T13:24:55.697684Z","end":"2026-04-09T13:24:56.005754Z","steps":["trace[437882643] 'process raft request' (duration: 307.601737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:56.005993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:24:55.697656Z","time spent":"308.234739ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T13:24:56.006013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.203499ms","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-09T13:24:56.006063Z","caller":"traceutil/trace.go:171","msg":"trace[1203225497] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2140; }","duration":"139.28127ms","start":"2026-04-09T13:24:55.866768Z","end":"2026-04-09T13:24:56.006049Z","steps":["trace[1203225497] 'agreement among raft nodes before linearized reading' (duration: 139.125658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:56.006022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.256993ms","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-09T13:24:56.00616Z","caller":"traceutil/trace.go:171","msg":"trace[1517700747] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2140; }","duration":"182.469557ms","start":"2026-04-09T13:24:55.823679Z","end":"2026-04-09T13:24:56.006148Z","steps":["trace[1517700747] 'agreement among raft nodes before linearized reading' (duration: 182.179462ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:56.208461Z","caller":"traceutil/trace.go:171","msg":"trace[1574758382] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"201.345991ms","start":"2026-04-09T13:24:56.007058Z","end":"2026-04-09T13:24:56.208404Z","steps":["trace[1574758382] 'process raft request' (duration: 188.382882ms)","trace[1574758382] 'compare' (duration: 12.722825ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:24:56.208571Z","caller":"traceutil/trace.go:171","msg":"trace[1487458565] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"196.120098ms","start":"2026-04-09T13:24:56.012403Z","end":"2026-04-09T13:24:56.208523Z","steps":["trace[1487458565] 'process raft request' (duration: 195.960075ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:24:56.208482Z","caller":"traceutil/trace.go:171","msg":"trace[2129786418] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2218; }","duration":"156.833136ms","start":"2026-04-09T13:24:56.051592Z","end":"2026-04-09T13:24:56.208425Z","steps":["trace[2129786418] 'read index received' (duration: 143.865908ms)","trace[2129786418] 'applied index is now lower than readState.Index' (duration: 12.965578ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T13:24:56.20892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.295194ms","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-09T13:24:56.208994Z","caller":"traceutil/trace.go:171","msg":"trace[1399674006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2142; }","duration":"157.440546ms","start":"2026-04-09T13:24:56.051539Z","end":"2026-04-09T13:24:56.20898Z","steps":["trace[1399674006] 'agreement among raft nodes before linearized reading' (duration: 157.023939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:56.209172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.235123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-09T13:24:56.209288Z","caller":"traceutil/trace.go:171","msg":"trace[476963361] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2142; }","duration":"157.392475ms","start":"2026-04-09T13:24:56.051882Z","end":"2026-04-09T13:24:56.209275Z","steps":["trace[476963361] 'agreement among raft nodes before linearized reading' (duration: 157.044179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:24:56.209353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.128821ms","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-09T13:24:56.20947Z","caller":"traceutil/trace.go:171","msg":"trace[765360133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2142; }","duration":"157.394095ms","start":"2026-04-09T13:24:56.052051Z","end":"2026-04-09T13:24:56.209445Z","steps":["trace[765360133] 'agreement among raft nodes before linearized reading' (duration: 156.974278ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:26:13.187389Z","caller":"traceutil/trace.go:171","msg":"trace[99452335] transaction","detail":"{read_only:false; response_revision:2653; number_of_response:1; }","duration":"118.505041ms","start":"2026-04-09T13:26:13.06885Z","end":"2026-04-09T13:26:13.187355Z","steps":["trace[99452335] 'process raft request' (duration: 118.257307ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:26:39.884517Z","caller":"traceutil/trace.go:171","msg":"trace[1362224277] transaction","detail":"{read_only:false; response_revision:2801; number_of_response:1; }","duration":"151.048244ms","start":"2026-04-09T13:26:39.733435Z","end":"2026-04-09T13:26:39.884483Z","steps":["trace[1362224277] 'process raft request' (duration: 150.846831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:26:45.841207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.356619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T13:26:45.842171Z","caller":"traceutil/trace.go:171","msg":"trace[823398913] transaction","detail":"{read_only:false; response_revision:2832; number_of_response:1; }","duration":"282.638115ms","start":"2026-04-09T13:26:45.55949Z","end":"2026-04-09T13:26:45.842128Z","steps":["trace[823398913] 'process raft request' (duration: 180.242683ms)","trace[823398913] 'compare' (duration: 101.093686ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T13:29:16.028859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.840914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-client-secret\" ","response":"range_response_count:1 size:1225"} {"level":"info","ts":"2026-04-09T13:29:16.028957Z","caller":"traceutil/trace.go:171","msg":"trace[1507617919] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-client-secret; range_end:; response_count:1; response_revision:3785; }","duration":"175.994278ms","start":"2026-04-09T13:29:15.852939Z","end":"2026-04-09T13:29:16.028933Z","steps":["trace[1507617919] 'range keys from in-memory index tree' (duration: 175.611089ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:29:54.077395Z","caller":"traceutil/trace.go:171","msg":"trace[390670875] linearizableReadLoop","detail":"{readStateIndex:4172; appliedIndex:4171; }","duration":"115.65959ms","start":"2026-04-09T13:29:53.96171Z","end":"2026-04-09T13:29:54.07737Z","steps":["trace[390670875] 'read index received' (duration: 19.274691ms)","trace[390670875] 'applied index is now lower than readState.Index' (duration: 96.383849ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:29:54.077556Z","caller":"traceutil/trace.go:171","msg":"trace[849630147] transaction","detail":"{read_only:false; response_revision:4021; number_of_response:1; }","duration":"117.786348ms","start":"2026-04-09T13:29:53.959708Z","end":"2026-04-09T13:29:54.077495Z","steps":["trace[849630147] 'process raft request' (duration: 21.519962ms)","trace[849630147] 'compare' (duration: 95.747704ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T13:29:54.077637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.904476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T13:29:54.077683Z","caller":"traceutil/trace.go:171","msg":"trace[1209638962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4021; }","duration":"116.012588ms","start":"2026-04-09T13:29:53.961659Z","end":"2026-04-09T13:29:54.077672Z","steps":["trace[1209638962] 'agreement among raft nodes before linearized reading' (duration: 115.845314ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:29:54.431831Z","caller":"traceutil/trace.go:171","msg":"trace[1448192676] transaction","detail":"{read_only:false; response_revision:4032; number_of_response:1; }","duration":"114.119893ms","start":"2026-04-09T13:29:54.317671Z","end":"2026-04-09T13:29:54.431791Z","steps":["trace[1448192676] 'process raft request' (duration: 29.585919ms)","trace[1448192676] 'compare' (duration: 84.298549ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:29:54.431946Z","caller":"traceutil/trace.go:171","msg":"trace[1054069734] linearizableReadLoop","detail":"{readStateIndex:4183; appliedIndex:4182; }","duration":"114.142814ms","start":"2026-04-09T13:29:54.317759Z","end":"2026-04-09T13:29:54.431901Z","steps":["trace[1054069734] 'read index received' (duration: 29.351374ms)","trace[1054069734] 'applied index is now lower than readState.Index' (duration: 84.78838ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T13:29:54.432278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.42277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusagents.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:29:54.432392Z","caller":"traceutil/trace.go:171","msg":"trace[10733636] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusagents.monitoring.coreos.com; range_end:; response_count:0; response_revision:4033; }","duration":"114.661005ms","start":"2026-04-09T13:29:54.317708Z","end":"2026-04-09T13:29:54.432369Z","steps":["trace[10733636] 'agreement among raft nodes before linearized reading' (duration: 114.396279ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:30:43.617225Z","caller":"traceutil/trace.go:171","msg":"trace[1669245437] transaction","detail":"{read_only:false; response_revision:4709; number_of_response:1; }","duration":"153.248927ms","start":"2026-04-09T13:30:43.463948Z","end":"2026-04-09T13:30:43.617197Z","steps":["trace[1669245437] 'process raft request' (duration: 153.093214ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:30:43.618182Z","caller":"traceutil/trace.go:171","msg":"trace[730287766] transaction","detail":"{read_only:false; response_revision:4708; number_of_response:1; }","duration":"186.71957ms","start":"2026-04-09T13:30:43.431057Z","end":"2026-04-09T13:30:43.617777Z","steps":["trace[730287766] 'process raft request' (duration: 183.458055ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:30:45.641057Z","caller":"traceutil/trace.go:171","msg":"trace[2019333189] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"103.311339ms","start":"2026-04-09T13:30:45.537727Z","end":"2026-04-09T13:30:45.641039Z","steps":["trace[2019333189] 'process raft request' (duration: 103.208877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:30:45.641633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.201696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-pjlvz-tls-p6fwj\" ","response":"range_response_count:1 size:2625"} {"level":"info","ts":"2026-04-09T13:30:45.641672Z","caller":"traceutil/trace.go:171","msg":"trace[832560882] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-pjlvz-tls-p6fwj; range_end:; response_count:1; response_revision:4771; }","duration":"104.263878ms","start":"2026-04-09T13:30:45.537398Z","end":"2026-04-09T13:30:45.641662Z","steps":["trace[832560882] 'agreement among raft nodes before linearized reading' (duration: 104.165376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:30:45.641896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.605914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-04-09T13:30:45.642021Z","caller":"traceutil/trace.go:171","msg":"trace[1980183645] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4771; }","duration":"105.767518ms","start":"2026-04-09T13:30:45.536231Z","end":"2026-04-09T13:30:45.641998Z","steps":["trace[1980183645] 'agreement among raft nodes before linearized reading' (duration: 105.395451ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:31:05.802377Z","caller":"traceutil/trace.go:171","msg":"trace[273576395] linearizableReadLoop","detail":"{readStateIndex:5240; appliedIndex:5239; }","duration":"242.88038ms","start":"2026-04-09T13:31:05.559462Z","end":"2026-04-09T13:31:05.802343Z","steps":["trace[273576395] 'read index received' (duration: 242.657166ms)","trace[273576395] 'applied index is now lower than readState.Index' (duration: 221.304µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:31:05.802446Z","caller":"traceutil/trace.go:171","msg":"trace[939881849] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"288.590538ms","start":"2026-04-09T13:31:05.513809Z","end":"2026-04-09T13:31:05.802399Z","steps":["trace[939881849] 'process raft request' (duration: 288.285613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:31:05.802702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.220166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-09T13:31:05.802757Z","caller":"traceutil/trace.go:171","msg":"trace[626986267] range","detail":"{range_begin:/registry/masterleases/199.19.213.132; range_end:; response_count:1; response_revision:5056; }","duration":"243.355999ms","start":"2026-04-09T13:31:05.559388Z","end":"2026-04-09T13:31:05.802744Z","steps":["trace[626986267] 'agreement among raft nodes before linearized reading' (duration: 243.148715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:31:06.054268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.645508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T13:31:06.054484Z","caller":"traceutil/trace.go:171","msg":"trace[371469708] linearizableReadLoop","detail":"{readStateIndex:5241; appliedIndex:5240; }","duration":"251.976601ms","start":"2026-04-09T13:31:05.802492Z","end":"2026-04-09T13:31:06.054468Z","steps":["trace[371469708] 'read index received' (duration: 102.088697ms)","trace[371469708] 'applied index is now lower than readState.Index' (duration: 149.865993ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:31:06.054549Z","caller":"traceutil/trace.go:171","msg":"trace[1423044978] transaction","detail":"{read_only:false; response_revision:5057; number_of_response:1; }","duration":"330.94806ms","start":"2026-04-09T13:31:05.723577Z","end":"2026-04-09T13:31:06.054525Z","steps":["trace[1423044978] 'process raft request' (duration: 180.973985ms)","trace[1423044978] 'compare' (duration: 149.101048ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T13:31:06.054693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:31:05.723546Z","time spent":"331.072682ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T13:31:06.05477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.465416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dbd4133b-6f05-4146-afd2-d33ca530c924\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-04-09T13:31:06.054805Z","caller":"traceutil/trace.go:171","msg":"trace[273363453] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dbd4133b-6f05-4146-afd2-d33ca530c924; range_end:; response_count:1; response_revision:5057; }","duration":"393.574519ms","start":"2026-04-09T13:31:05.66122Z","end":"2026-04-09T13:31:06.054795Z","steps":["trace[273363453] 'agreement among raft nodes before linearized reading' (duration: 393.297344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:31:06.054836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:31:05.661188Z","time spent":"393.640119ms","remote":"127.0.0.1:36118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3788,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dbd4133b-6f05-4146-afd2-d33ca530c924\" "} {"level":"warn","ts":"2026-04-09T13:31:06.054939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.042646ms","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-09T13:31:06.054993Z","caller":"traceutil/trace.go:171","msg":"trace[2108728048] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:5057; }","duration":"327.130847ms","start":"2026-04-09T13:31:05.727845Z","end":"2026-04-09T13:31:06.054976Z","steps":["trace[2108728048] 'agreement among raft nodes before linearized reading' (duration: 326.688659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:31:06.055037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T13:31:05.727825Z","time spent":"327.199859ms","remote":"127.0.0.1:36418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":11,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-04-09T13:31:59.691057Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2907} {"level":"info","ts":"2026-04-09T13:31:59.828321Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2907,"took":"133.187124ms","hash":1814020729,"current-db-size-bytes":54120448,"current-db-size":"54 MB","current-db-size-in-use-bytes":29061120,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-09T13:31:59.828391Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1814020729,"revision":2907,"compact-revision":-1} {"level":"info","ts":"2026-04-09T13:34:52.245076Z","caller":"traceutil/trace.go:171","msg":"trace[881590705] linearizableReadLoop","detail":"{readStateIndex:6946; appliedIndex:6944; }","duration":"104.852634ms","start":"2026-04-09T13:34:52.140195Z","end":"2026-04-09T13:34:52.245047Z","steps":["trace[881590705] 'read index received' (duration: 10.130575ms)","trace[881590705] 'applied index is now lower than readState.Index' (duration: 94.720829ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T13:34:52.245184Z","caller":"traceutil/trace.go:171","msg":"trace[68687622] transaction","detail":"{read_only:false; response_revision:6708; number_of_response:1; }","duration":"116.599483ms","start":"2026-04-09T13:34:52.128548Z","end":"2026-04-09T13:34:52.245148Z","steps":["trace[68687622] 'process raft request' (duration: 115.835032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T13:34:52.245476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.25883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T13:34:52.245621Z","caller":"traceutil/trace.go:171","msg":"trace[799366935] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:6708; }","duration":"105.364852ms","start":"2026-04-09T13:34:52.140153Z","end":"2026-04-09T13:34:52.245518Z","steps":["trace[799366935] 'agreement among raft nodes before linearized reading' (duration: 105.043147ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:35:22.501117Z","caller":"traceutil/trace.go:171","msg":"trace[15028539] transaction","detail":"{read_only:false; response_revision:6928; number_of_response:1; }","duration":"136.219381ms","start":"2026-04-09T13:35:22.364879Z","end":"2026-04-09T13:35:22.501099Z","steps":["trace[15028539] 'process raft request' (duration: 40.547063ms)","trace[15028539] 'compare' (duration: 95.487455ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T13:35:22.761223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.123013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T13:35:22.76133Z","caller":"traceutil/trace.go:171","msg":"trace[15152310] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:6928; }","duration":"156.273005ms","start":"2026-04-09T13:35:22.605022Z","end":"2026-04-09T13:35:22.761295Z","steps":["trace[15152310] 'count revisions from in-memory index tree' (duration: 156.016342ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T13:36:59.698271Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5511} {"level":"info","ts":"2026-04-09T13:36:59.781624Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5511,"took":"81.995987ms","hash":4257868032,"current-db-size-bytes":54120448,"current-db-size":"54 MB","current-db-size-in-use-bytes":18874368,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-04-09T13:36:59.781675Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4257868032,"revision":5511,"compact-revision":2907} {"level":"info","ts":"2026-04-09T13:41:59.703412Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7442} {"level":"info","ts":"2026-04-09T13:41:59.760306Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7442,"took":"55.206503ms","hash":2832109626,"current-db-size-bytes":54120448,"current-db-size":"54 MB","current-db-size-in-use-bytes":15253504,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-04-09T13:41:59.760447Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2832109626,"revision":7442,"compact-revision":5511}