{"level":"warn","ts":"2026-04-02T08:45:42.994891Z","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-02T08:45:42.995058Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.87: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.87:2380","--initial-cluster=instance=https://199.19.213.87:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.87:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.87: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-02T08:45:42.995184Z","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-02T08:45:42.995207Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.87:2380"]} {"level":"info","ts":"2026-04-02T08:45:42.995245Z","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-02T08:45:42.996247Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.87:2379"]} {"level":"info","ts":"2026-04-02T08:45:42.996384Z","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.87:2380"],"listen-peer-urls":["https://199.19.213.87:2380"],"advertise-client-urls":["https://199.19.213.87:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.87:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.87: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-02T08:45:43.340763Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"344.021412ms"} {"level":"info","ts":"2026-04-02T08:45:43.386312Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bc3cb673a921c717","cluster-id":"f315f77360ac6549"} {"level":"info","ts":"2026-04-02T08:45:43.386445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T08:45:43.386472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became follower at term 0"} {"level":"info","ts":"2026-04-02T08:45:43.386481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bc3cb673a921c717 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T08:45:43.386491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became follower at term 1"} {"level":"info","ts":"2026-04-02T08:45:43.386524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 switched to configuration voters=(13563916785608148759)"} {"level":"warn","ts":"2026-04-02T08:45:43.401457Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T08:45:43.405599Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T08:45:43.410119Z","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-02T08:45:43.413161Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bc3cb673a921c717","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T08:45:43.413476Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bc3cb673a921c717","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T08:45:43.414969Z","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-02T08:45:43.41506Z","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-02T08:45:43.415073Z","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-02T08:45:43.415048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 switched to configuration voters=(13563916785608148759)"} {"level":"info","ts":"2026-04-02T08:45:43.415861Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f315f77360ac6549","local-member-id":"bc3cb673a921c717","added-peer-id":"bc3cb673a921c717","added-peer-peer-urls":["https://199.19.213.87:2380"]} {"level":"info","ts":"2026-04-02T08:45:43.421274Z","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-02T08:45:43.421416Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.87:2380"} {"level":"info","ts":"2026-04-02T08:45:43.421456Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.87:2380"} {"level":"info","ts":"2026-04-02T08:45:43.421478Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bc3cb673a921c717","initial-advertise-peer-urls":["https://199.19.213.87:2380"],"listen-peer-urls":["https://199.19.213.87:2380"],"advertise-client-urls":["https://199.19.213.87:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.87:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T08:45:43.421515Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T08:45:44.188371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T08:45:44.188443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T08:45:44.188481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 received MsgPreVoteResp from bc3cb673a921c717 at term 1"} {"level":"info","ts":"2026-04-02T08:45:44.188502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became candidate at term 2"} {"level":"info","ts":"2026-04-02T08:45:44.188519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 received MsgVoteResp from bc3cb673a921c717 at term 2"} {"level":"info","ts":"2026-04-02T08:45:44.188536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became leader at term 2"} {"level":"info","ts":"2026-04-02T08:45:44.188561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bc3cb673a921c717 elected leader bc3cb673a921c717 at term 2"} {"level":"info","ts":"2026-04-02T08:45:44.18956Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bc3cb673a921c717","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.87:2379]}","request-path":"/0/members/bc3cb673a921c717/attributes","cluster-id":"f315f77360ac6549","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T08:45:44.189785Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:45:44.190449Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T08:45:44.190758Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T08:45:44.190807Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T08:45:44.190858Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T08:45:44.190886Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f315f77360ac6549","local-member-id":"bc3cb673a921c717","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:45:44.191019Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:45:44.191061Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:45:44.192337Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T08:45:44.193506Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.87:2379"} {"level":"info","ts":"2026-04-02T08:45:45.722391Z","caller":"traceutil/trace.go:171","msg":"trace[1505476354] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"135.156163ms","start":"2026-04-02T08:45:45.587199Z","end":"2026-04-02T08:45:45.722355Z","steps":["trace[1505476354] 'process raft request' (duration: 135.05983ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:45.722473Z","caller":"traceutil/trace.go:171","msg":"trace[183504693] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"133.684212ms","start":"2026-04-02T08:45:45.588767Z","end":"2026-04-02T08:45:45.722451Z","steps":["trace[183504693] 'process raft request' (duration: 133.568889ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:45.722584Z","caller":"traceutil/trace.go:171","msg":"trace[1131006978] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"133.782415ms","start":"2026-04-02T08:45:45.588771Z","end":"2026-04-02T08:45:45.722553Z","steps":["trace[1131006978] 'process raft request' (duration: 133.60602ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:45.722708Z","caller":"traceutil/trace.go:171","msg":"trace[703943240] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"134.058313ms","start":"2026-04-02T08:45:45.588635Z","end":"2026-04-02T08:45:45.722693Z","steps":["trace[703943240] 'process raft request' (duration: 133.650831ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:45.722753Z","caller":"traceutil/trace.go:171","msg":"trace[1165036427] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"133.821246ms","start":"2026-04-02T08:45:45.58892Z","end":"2026-04-02T08:45:45.722742Z","steps":["trace[1165036427] 'process raft request' (duration: 133.521188ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:45.722749Z","caller":"traceutil/trace.go:171","msg":"trace[1895931628] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"135.40402ms","start":"2026-04-02T08:45:45.587131Z","end":"2026-04-02T08:45:45.722535Z","steps":["trace[1895931628] 'process raft request' (duration: 135.082701ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:45.722703Z","caller":"traceutil/trace.go:171","msg":"trace[712318894] transaction","detail":"{read_only:false; number_of_response:0; response_revision:12; }","duration":"133.891228ms","start":"2026-04-02T08:45:45.588784Z","end":"2026-04-02T08:45:45.722675Z","steps":["trace[712318894] 'process raft request' (duration: 133.628651ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:45.722913Z","caller":"traceutil/trace.go:171","msg":"trace[1998754000] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"132.713784ms","start":"2026-04-02T08:45:45.590181Z","end":"2026-04-02T08:45:45.722895Z","steps":["trace[1998754000] 'process raft request' (duration: 132.331604ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:45.722949Z","caller":"traceutil/trace.go:171","msg":"trace[1562275075] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"113.503284ms","start":"2026-04-02T08:45:45.609434Z","end":"2026-04-02T08:45:45.722937Z","steps":["trace[1562275075] 'process raft request' (duration: 113.124344ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:45.722341Z","caller":"traceutil/trace.go:171","msg":"trace[1307306135] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"135.340259ms","start":"2026-04-02T08:45:45.586964Z","end":"2026-04-02T08:45:45.722304Z","steps":["trace[1307306135] 'process raft request' (duration: 76.451401ms)","trace[1307306135] 'compare' (duration: 58.685072ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:46.645291Z","caller":"traceutil/trace.go:171","msg":"trace[1114041085] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"125.454032ms","start":"2026-04-02T08:45:46.519807Z","end":"2026-04-02T08:45:46.645261Z","steps":["trace[1114041085] 'process raft request' (duration: 53.020424ms)","trace[1114041085] 'compare' (duration: 72.228802ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:46.90444Z","caller":"traceutil/trace.go:171","msg":"trace[1206733931] transaction","detail":"{read_only:false; response_revision:119; number_of_response:1; }","duration":"120.668456ms","start":"2026-04-02T08:45:46.783741Z","end":"2026-04-02T08:45:46.904409Z","steps":["trace[1206733931] 'process raft request' (duration: 119.641998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:47.206326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.995272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T08:45:47.206442Z","caller":"traceutil/trace.go:171","msg":"trace[1725168281] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:121; }","duration":"199.189518ms","start":"2026-04-02T08:45:47.007227Z","end":"2026-04-02T08:45:47.206417Z","steps":["trace[1725168281] 'range keys from in-memory index tree' (duration: 198.881389ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:47.317356Z","caller":"traceutil/trace.go:171","msg":"trace[1012616452] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"107.136714ms","start":"2026-04-02T08:45:47.210178Z","end":"2026-04-02T08:45:47.317315Z","steps":["trace[1012616452] 'process raft request' (duration: 107.00916ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:47.476658Z","caller":"traceutil/trace.go:171","msg":"trace[177763126] transaction","detail":"{read_only:false; response_revision:124; number_of_response:1; }","duration":"149.324919ms","start":"2026-04-02T08:45:47.3273Z","end":"2026-04-02T08:45:47.476625Z","steps":["trace[177763126] 'process raft request' (duration: 105.869298ms)","trace[177763126] 'compare' (duration: 43.263715ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:47.663684Z","caller":"traceutil/trace.go:171","msg":"trace[1344812793] transaction","detail":"{read_only:false; response_revision:126; number_of_response:1; }","duration":"114.18844ms","start":"2026-04-02T08:45:47.549464Z","end":"2026-04-02T08:45:47.663652Z","steps":["trace[1344812793] 'process raft request' (duration: 73.863097ms)","trace[1344812793] 'compare' (duration: 39.948733ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:52.862241Z","caller":"traceutil/trace.go:171","msg":"trace[1484051551] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:327; }","duration":"257.333342ms","start":"2026-04-02T08:45:52.604884Z","end":"2026-04-02T08:45:52.862218Z","steps":["trace[1484051551] 'read index received' (duration: 231.194792ms)","trace[1484051551] 'applied index is now lower than readState.Index' (duration: 26.1377ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:52.862333Z","caller":"traceutil/trace.go:171","msg":"trace[1407660695] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"278.629741ms","start":"2026-04-02T08:45:52.583695Z","end":"2026-04-02T08:45:52.862324Z","steps":["trace[1407660695] 'process raft request' (duration: 252.491551ms)","trace[1407660695] 'compare' (duration: 25.943745ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:45:52.862454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.564089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:45:52.86255Z","caller":"traceutil/trace.go:171","msg":"trace[2111480294] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:0; response_revision:316; }","duration":"257.672332ms","start":"2026-04-02T08:45:52.604799Z","end":"2026-04-02T08:45:52.862472Z","steps":["trace[2111480294] 'agreement among raft nodes before linearized reading' (duration: 257.620501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:52.862816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.89646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-04-02T08:45:52.862844Z","caller":"traceutil/trace.go:171","msg":"trace[614758230] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:316; }","duration":"189.969982ms","start":"2026-04-02T08:45:52.672868Z","end":"2026-04-02T08:45:52.862838Z","steps":["trace[614758230] 'agreement among raft nodes before linearized reading' (duration: 189.915331ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:52.988135Z","caller":"traceutil/trace.go:171","msg":"trace[102103085] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"117.022939ms","start":"2026-04-02T08:45:52.87108Z","end":"2026-04-02T08:45:52.988103Z","steps":["trace[102103085] 'process raft request' (duration: 109.69332ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:53.173908Z","caller":"traceutil/trace.go:171","msg":"trace[681938911] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"168.207559ms","start":"2026-04-02T08:45:53.005673Z","end":"2026-04-02T08:45:53.17388Z","steps":["trace[681938911] 'process raft request' (duration: 87.456805ms)","trace[681938911] 'compare' (duration: 80.60801ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:45:53.584038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.946212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:45:53.584135Z","caller":"traceutil/trace.go:171","msg":"trace[1102254461] linearizableReadLoop","detail":"{readStateIndex:334; appliedIndex:333; }","duration":"243.494445ms","start":"2026-04-02T08:45:53.34063Z","end":"2026-04-02T08:45:53.584124Z","steps":["trace[1102254461] 'read index received' (duration: 119.103551ms)","trace[1102254461] 'applied index is now lower than readState.Index' (duration: 124.390044ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:45:53.584197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.567497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-04-02T08:45:53.584215Z","caller":"traceutil/trace.go:171","msg":"trace[1186295661] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:322; }","duration":"243.610108ms","start":"2026-04-02T08:45:53.340599Z","end":"2026-04-02T08:45:53.584209Z","steps":["trace[1186295661] 'agreement among raft nodes before linearized reading' (duration: 243.553257ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:53.584236Z","caller":"traceutil/trace.go:171","msg":"trace[1530065272] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"277.746731ms","start":"2026-04-02T08:45:53.306415Z","end":"2026-04-02T08:45:53.584161Z","steps":["trace[1530065272] 'process raft request' (duration: 153.326156ms)","trace[1530065272] 'compare' (duration: 123.799688ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:53.730317Z","caller":"traceutil/trace.go:171","msg":"trace[584480844] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"120.052116ms","start":"2026-04-02T08:45:53.610236Z","end":"2026-04-02T08:45:53.730289Z","steps":["trace[584480844] 'process raft request' (duration: 119.796119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:54.645156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.91092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-02T08:45:54.645274Z","caller":"traceutil/trace.go:171","msg":"trace[1746532751] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:332; }","duration":"210.106815ms","start":"2026-04-02T08:45:54.43514Z","end":"2026-04-02T08:45:54.645247Z","steps":["trace[1746532751] 'range keys from in-memory index tree' (duration: 209.769595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:54.645273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.368337ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:45:54.645405Z","caller":"traceutil/trace.go:171","msg":"trace[710817759] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:332; }","duration":"189.532271ms","start":"2026-04-02T08:45:54.455851Z","end":"2026-04-02T08:45:54.645383Z","steps":["trace[710817759] 'range keys from in-memory index tree' (duration: 189.267764ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:56.272868Z","caller":"traceutil/trace.go:171","msg":"trace[462618623] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:363; }","duration":"120.344949ms","start":"2026-04-02T08:45:56.152492Z","end":"2026-04-02T08:45:56.272836Z","steps":["trace[462618623] 'read index received' (duration: 80.456845ms)","trace[462618623] 'applied index is now lower than readState.Index' (duration: 39.886864ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:56.272919Z","caller":"traceutil/trace.go:171","msg":"trace[1529328032] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"120.952865ms","start":"2026-04-02T08:45:56.151938Z","end":"2026-04-02T08:45:56.272891Z","steps":["trace[1529328032] 'process raft request' (duration: 81.03266ms)","trace[1529328032] 'compare' (duration: 39.672488ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:45:56.273125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.555555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-02T08:45:56.273328Z","caller":"traceutil/trace.go:171","msg":"trace[1434389390] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:352; }","duration":"120.836022ms","start":"2026-04-02T08:45:56.152456Z","end":"2026-04-02T08:45:56.273292Z","steps":["trace[1434389390] 'agreement among raft nodes before linearized reading' (duration: 120.481543ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:56.276117Z","caller":"traceutil/trace.go:171","msg":"trace[881803971] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"121.873629ms","start":"2026-04-02T08:45:56.154223Z","end":"2026-04-02T08:45:56.276097Z","steps":["trace[881803971] 'process raft request' (duration: 121.695974ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:56.921349Z","caller":"traceutil/trace.go:171","msg":"trace[788570666] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:371; }","duration":"217.450209ms","start":"2026-04-02T08:45:56.703878Z","end":"2026-04-02T08:45:56.921328Z","steps":["trace[788570666] 'read index received' (duration: 48.546445ms)","trace[788570666] 'applied index is now lower than readState.Index' (duration: 168.902874ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:56.921444Z","caller":"traceutil/trace.go:171","msg":"trace[1429806406] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"255.812072ms","start":"2026-04-02T08:45:56.66561Z","end":"2026-04-02T08:45:56.921422Z","steps":["trace[1429806406] 'process raft request' (duration: 248.348723ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:56.92154Z","caller":"traceutil/trace.go:171","msg":"trace[1137169464] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"255.900485ms","start":"2026-04-02T08:45:56.665627Z","end":"2026-04-02T08:45:56.921528Z","steps":["trace[1137169464] 'process raft request' (duration: 255.653098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:56.92168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.35552ms","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-02T08:45:56.921743Z","caller":"traceutil/trace.go:171","msg":"trace[803962702] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:362; }","duration":"168.557825ms","start":"2026-04-02T08:45:56.753166Z","end":"2026-04-02T08:45:56.921724Z","steps":["trace[803962702] 'agreement among raft nodes before linearized reading' (duration: 168.441782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:56.922072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.131851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-02T08:45:56.922123Z","caller":"traceutil/trace.go:171","msg":"trace[1444976543] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:362; }","duration":"118.221773ms","start":"2026-04-02T08:45:56.803884Z","end":"2026-04-02T08:45:56.922106Z","steps":["trace[1444976543] 'agreement among raft nodes before linearized reading' (duration: 118.144391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:56.922279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.389974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-02T08:45:56.922336Z","caller":"traceutil/trace.go:171","msg":"trace[895173826] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:362; }","duration":"218.472087ms","start":"2026-04-02T08:45:56.703847Z","end":"2026-04-02T08:45:56.922319Z","steps":["trace[895173826] 'agreement among raft nodes before linearized reading' (duration: 218.366994ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.099891Z","caller":"traceutil/trace.go:171","msg":"trace[2100856007] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"171.663545ms","start":"2026-04-02T08:45:56.928133Z","end":"2026-04-02T08:45:57.099797Z","steps":["trace[2100856007] 'process raft request' (duration: 123.393614ms)","trace[2100856007] 'compare' (duration: 47.880521ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:57.099944Z","caller":"traceutil/trace.go:171","msg":"trace[2053873970] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"169.870347ms","start":"2026-04-02T08:45:56.930045Z","end":"2026-04-02T08:45:57.099915Z","steps":["trace[2053873970] 'process raft request' (duration: 169.517878ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.100118Z","caller":"traceutil/trace.go:171","msg":"trace[89425762] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"169.336934ms","start":"2026-04-02T08:45:56.930748Z","end":"2026-04-02T08:45:57.100085Z","steps":["trace[89425762] 'process raft request' (duration: 168.889722ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.10064Z","caller":"traceutil/trace.go:171","msg":"trace[1984274346] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"169.290103ms","start":"2026-04-02T08:45:56.930932Z","end":"2026-04-02T08:45:57.100222Z","steps":["trace[1984274346] 'process raft request' (duration: 168.987254ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.100698Z","caller":"traceutil/trace.go:171","msg":"trace[948890112] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"168.178233ms","start":"2026-04-02T08:45:56.932511Z","end":"2026-04-02T08:45:57.100689Z","steps":["trace[948890112] 'process raft request' (duration: 167.520665ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.101373Z","caller":"traceutil/trace.go:171","msg":"trace[33158618] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:375; }","duration":"147.07006ms","start":"2026-04-02T08:45:56.954282Z","end":"2026-04-02T08:45:57.101352Z","steps":["trace[33158618] 'read index received' (duration: 97.182006ms)","trace[33158618] 'applied index is now lower than readState.Index' (duration: 49.886904ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:57.101444Z","caller":"traceutil/trace.go:171","msg":"trace[309252703] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"168.83668ms","start":"2026-04-02T08:45:56.932589Z","end":"2026-04-02T08:45:57.101426Z","steps":["trace[309252703] 'process raft request' (duration: 167.513864ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.102317Z","caller":"traceutil/trace.go:171","msg":"trace[213554875] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"168.794779ms","start":"2026-04-02T08:45:56.933513Z","end":"2026-04-02T08:45:57.102308Z","steps":["trace[213554875] 'process raft request' (duration: 167.771452ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.102202Z","caller":"traceutil/trace.go:171","msg":"trace[1998399853] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"169.21501ms","start":"2026-04-02T08:45:56.932965Z","end":"2026-04-02T08:45:57.10218Z","steps":["trace[1998399853] 'process raft request' (duration: 168.230813ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.102239Z","caller":"traceutil/trace.go:171","msg":"trace[1743958208] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"169.411155ms","start":"2026-04-02T08:45:56.932821Z","end":"2026-04-02T08:45:57.102232Z","steps":["trace[1743958208] 'process raft request' (duration: 168.293535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:57.105317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.007204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-02T08:45:57.105527Z","caller":"traceutil/trace.go:171","msg":"trace[562398501] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:372; }","duration":"151.2283ms","start":"2026-04-02T08:45:56.954256Z","end":"2026-04-02T08:45:57.105484Z","steps":["trace[562398501] 'agreement among raft nodes before linearized reading' (duration: 148.062386ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.27675Z","caller":"traceutil/trace.go:171","msg":"trace[222994238] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"105.354937ms","start":"2026-04-02T08:45:57.171305Z","end":"2026-04-02T08:45:57.27666Z","steps":["trace[222994238] 'process raft request' (duration: 32.575205ms)","trace[222994238] 'compare' (duration: 72.613348ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:57.304405Z","caller":"traceutil/trace.go:171","msg":"trace[1015959062] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"132.667272ms","start":"2026-04-02T08:45:57.171702Z","end":"2026-04-02T08:45:57.304369Z","steps":["trace[1015959062] 'process raft request' (duration: 132.395794ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.3048Z","caller":"traceutil/trace.go:171","msg":"trace[1657422788] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"132.774994ms","start":"2026-04-02T08:45:57.171992Z","end":"2026-04-02T08:45:57.304767Z","steps":["trace[1657422788] 'process raft request' (duration: 132.327772ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.304873Z","caller":"traceutil/trace.go:171","msg":"trace[376826081] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"112.393203ms","start":"2026-04-02T08:45:57.192459Z","end":"2026-04-02T08:45:57.304853Z","steps":["trace[376826081] 'process raft request' (duration: 112.309531ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.305026Z","caller":"traceutil/trace.go:171","msg":"trace[2063390751] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"112.914258ms","start":"2026-04-02T08:45:57.192099Z","end":"2026-04-02T08:45:57.305014Z","steps":["trace[2063390751] 'process raft request' (duration: 112.590269ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.305058Z","caller":"traceutil/trace.go:171","msg":"trace[1097023294] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:389; }","duration":"110.663547ms","start":"2026-04-02T08:45:57.194372Z","end":"2026-04-02T08:45:57.305036Z","steps":["trace[1097023294] 'read index received' (duration: 9.524643ms)","trace[1097023294] 'applied index is now lower than readState.Index' (duration: 101.137234ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:57.305162Z","caller":"traceutil/trace.go:171","msg":"trace[1741930055] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"112.332632ms","start":"2026-04-02T08:45:57.192814Z","end":"2026-04-02T08:45:57.305147Z","steps":["trace[1741930055] 'process raft request' (duration: 112.008083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:57.305247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.864423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-02T08:45:57.305281Z","caller":"traceutil/trace.go:171","msg":"trace[1935521185] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:383; }","duration":"110.929224ms","start":"2026-04-02T08:45:57.19434Z","end":"2026-04-02T08:45:57.305269Z","steps":["trace[1935521185] 'agreement among raft nodes before linearized reading' (duration: 110.828912ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.463165Z","caller":"traceutil/trace.go:171","msg":"trace[1818577031] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"123.221421ms","start":"2026-04-02T08:45:57.339931Z","end":"2026-04-02T08:45:57.463153Z","steps":["trace[1818577031] 'process raft request' (duration: 122.994775ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.463073Z","caller":"traceutil/trace.go:171","msg":"trace[1954801019] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"127.34457ms","start":"2026-04-02T08:45:57.335676Z","end":"2026-04-02T08:45:57.46302Z","steps":["trace[1954801019] 'process raft request' (duration: 77.324902ms)","trace[1954801019] 'compare' (duration: 49.780572ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:57.465851Z","caller":"traceutil/trace.go:171","msg":"trace[395991433] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"115.608939ms","start":"2026-04-02T08:45:57.350208Z","end":"2026-04-02T08:45:57.465817Z","steps":["trace[395991433] 'process raft request' (duration: 115.016343ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.466Z","caller":"traceutil/trace.go:171","msg":"trace[1871088472] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"114.579721ms","start":"2026-04-02T08:45:57.35139Z","end":"2026-04-02T08:45:57.46597Z","steps":["trace[1871088472] 'process raft request' (duration: 114.043957ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.466089Z","caller":"traceutil/trace.go:171","msg":"trace[1184032867] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"115.350312ms","start":"2026-04-02T08:45:57.35073Z","end":"2026-04-02T08:45:57.46608Z","steps":["trace[1184032867] 'process raft request' (duration: 114.658053ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.466242Z","caller":"traceutil/trace.go:171","msg":"trace[1898590197] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"109.60675ms","start":"2026-04-02T08:45:57.356623Z","end":"2026-04-02T08:45:57.46623Z","steps":["trace[1898590197] 'process raft request' (duration: 108.86656ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:57.939958Z","caller":"traceutil/trace.go:171","msg":"trace[1685570198] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:408; }","duration":"360.300974ms","start":"2026-04-02T08:45:57.579638Z","end":"2026-04-02T08:45:57.939939Z","steps":["trace[1685570198] 'read index received' (duration: 360.121669ms)","trace[1685570198] 'applied index is now lower than readState.Index' (duration: 178.315µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:57.940063Z","caller":"traceutil/trace.go:171","msg":"trace[1892503156] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"372.507766ms","start":"2026-04-02T08:45:57.567509Z","end":"2026-04-02T08:45:57.940017Z","steps":["trace[1892503156] 'process raft request' (duration: 372.3016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:57.940185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.437717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:45:57.940355Z","caller":"traceutil/trace.go:171","msg":"trace[675509356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:397; }","duration":"360.732655ms","start":"2026-04-02T08:45:57.579596Z","end":"2026-04-02T08:45:57.940328Z","steps":["trace[675509356] 'agreement among raft nodes before linearized reading' (duration: 360.450038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:57.940431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:57.57958Z","time spent":"360.827387ms","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T08:45:57.940692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:57.567488Z","time spent":"372.753433ms","remote":"127.0.0.1:59398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4041,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:45:58.402274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.428468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:45:58.402381Z","caller":"traceutil/trace.go:171","msg":"trace[1497502980] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:410; }","duration":"337.876626ms","start":"2026-04-02T08:45:58.064488Z","end":"2026-04-02T08:45:58.402364Z","steps":["trace[1497502980] 'read index received' (duration: 132.224963ms)","trace[1497502980] 'applied index is now lower than readState.Index' (duration: 205.650663ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:45:58.402509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.01761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T08:45:58.402468Z","caller":"traceutil/trace.go:171","msg":"trace[206391538] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"458.5115ms","start":"2026-04-02T08:45:57.943917Z","end":"2026-04-02T08:45:58.402428Z","steps":["trace[206391538] 'process raft request' (duration: 252.851837ms)","trace[206391538] 'compare' (duration: 205.302394ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:58.402642Z","caller":"traceutil/trace.go:171","msg":"trace[702921795] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:399; }","duration":"338.093142ms","start":"2026-04-02T08:45:58.064444Z","end":"2026-04-02T08:45:58.402537Z","steps":["trace[702921795] 'agreement among raft nodes before linearized reading' (duration: 337.965438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:58.402718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:58.064423Z","time spent":"338.282487ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T08:45:58.402771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:57.94387Z","time spent":"458.756406ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:46:00.893423Z","caller":"traceutil/trace.go:171","msg":"trace[1621320258] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"103.135591ms","start":"2026-04-02T08:46:00.790263Z","end":"2026-04-02T08:46:00.893399Z","steps":["trace[1621320258] 'process raft request' (duration: 102.989497ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:02.624851Z","caller":"traceutil/trace.go:171","msg":"trace[194174701] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"195.020944ms","start":"2026-04-02T08:46:02.42981Z","end":"2026-04-02T08:46:02.624831Z","steps":["trace[194174701] 'read index received' (duration: 194.848059ms)","trace[194174701] 'applied index is now lower than readState.Index' (duration: 172.035µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:46:02.624995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.184987ms","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-02T08:46:02.624995Z","caller":"traceutil/trace.go:171","msg":"trace[1568007148] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"609.400359ms","start":"2026-04-02T08:46:02.01555Z","end":"2026-04-02T08:46:02.62495Z","steps":["trace[1568007148] 'process raft request' (duration: 609.156513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:02.62519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:02.015532Z","time spent":"609.552583ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:46:02.625668Z","caller":"traceutil/trace.go:171","msg":"trace[1073348429] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:416; }","duration":"195.29707ms","start":"2026-04-02T08:46:02.42975Z","end":"2026-04-02T08:46:02.625047Z","steps":["trace[1073348429] 'agreement among raft nodes before linearized reading' (duration: 195.197058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:04.163069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.736262ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-04-02T08:46:04.80993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.239263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T08:46:04.810073Z","caller":"traceutil/trace.go:171","msg":"trace[1313462867] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"104.431378ms","start":"2026-04-02T08:46:04.705622Z","end":"2026-04-02T08:46:04.810053Z","steps":["trace[1313462867] 'range keys from in-memory index tree' (duration: 104.112719ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:09.085365Z","caller":"traceutil/trace.go:171","msg":"trace[1644231137] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"155.07871ms","start":"2026-04-02T08:46:08.930267Z","end":"2026-04-02T08:46:09.085346Z","steps":["trace[1644231137] 'process raft request' (duration: 154.961417ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:09.089325Z","caller":"traceutil/trace.go:171","msg":"trace[502011254] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"158.496786ms","start":"2026-04-02T08:46:08.930806Z","end":"2026-04-02T08:46:09.089303Z","steps":["trace[502011254] 'process raft request' (duration: 158.377343ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:11.46706Z","caller":"traceutil/trace.go:171","msg":"trace[1643903581] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"118.316392ms","start":"2026-04-02T08:46:11.34872Z","end":"2026-04-02T08:46:11.467036Z","steps":["trace[1643903581] 'process raft request' (duration: 118.164028ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:12.943129Z","caller":"traceutil/trace.go:171","msg":"trace[123183538] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"103.397817ms","start":"2026-04-02T08:46:12.83971Z","end":"2026-04-02T08:46:12.943108Z","steps":["trace[123183538] 'process raft request' (duration: 103.276464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:14.164569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.833496ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-02T08:46:31.801842Z","caller":"traceutil/trace.go:171","msg":"trace[1184177022] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"135.928686ms","start":"2026-04-02T08:46:31.66589Z","end":"2026-04-02T08:46:31.801818Z","steps":["trace[1184177022] 'process raft request' (duration: 135.801673ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:33.665317Z","caller":"traceutil/trace.go:171","msg":"trace[1365712125] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"100.288789ms","start":"2026-04-02T08:46:33.564994Z","end":"2026-04-02T08:46:33.665282Z","steps":["trace[1365712125] 'process raft request' (duration: 77.91365ms)","trace[1365712125] 'compare' (duration: 22.210826ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:45.490133Z","caller":"traceutil/trace.go:171","msg":"trace[1596827989] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"228.146669ms","start":"2026-04-02T08:46:45.261961Z","end":"2026-04-02T08:46:45.490108Z","steps":["trace[1596827989] 'process raft request' (duration: 227.955194ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:45.653127Z","caller":"traceutil/trace.go:171","msg":"trace[487844556] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"157.429089ms","start":"2026-04-02T08:46:45.495681Z","end":"2026-04-02T08:46:45.65311Z","steps":["trace[487844556] 'process raft request' (duration: 157.140152ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:45.653149Z","caller":"traceutil/trace.go:171","msg":"trace[292192529] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"157.974591ms","start":"2026-04-02T08:46:45.49511Z","end":"2026-04-02T08:46:45.653084Z","steps":["trace[292192529] 'process raft request' (duration: 123.21013ms)","trace[292192529] 'compare' (duration: 34.372142ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:47.607865Z","caller":"traceutil/trace.go:171","msg":"trace[1783963066] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"107.887803ms","start":"2026-04-02T08:46:47.499951Z","end":"2026-04-02T08:46:47.607839Z","steps":["trace[1783963066] 'read index received' (duration: 66.802884ms)","trace[1783963066] 'applied index is now lower than readState.Index' (duration: 41.083979ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:47.607973Z","caller":"traceutil/trace.go:171","msg":"trace[712317205] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"109.471779ms","start":"2026-04-02T08:46:47.498469Z","end":"2026-04-02T08:46:47.60794Z","steps":["trace[712317205] 'process raft request' (duration: 68.279767ms)","trace[712317205] 'compare' (duration: 40.971327ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:46:47.608119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.150929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-04-02T08:46:47.608159Z","caller":"traceutil/trace.go:171","msg":"trace[1870760055] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:581; }","duration":"108.235801ms","start":"2026-04-02T08:46:47.499911Z","end":"2026-04-02T08:46:47.608147Z","steps":["trace[1870760055] 'agreement among raft nodes before linearized reading' (duration: 108.071898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:47.608207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.626537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-04-02T08:46:47.608278Z","caller":"traceutil/trace.go:171","msg":"trace[684273358] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:581; }","duration":"107.73043ms","start":"2026-04-02T08:46:47.500527Z","end":"2026-04-02T08:46:47.608258Z","steps":["trace[684273358] 'agreement among raft nodes before linearized reading' (duration: 107.568216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:49.256346Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346108093638402095,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T08:46:49.359179Z","caller":"traceutil/trace.go:171","msg":"trace[1110411399] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"620.162457ms","start":"2026-04-02T08:46:48.738989Z","end":"2026-04-02T08:46:49.359151Z","steps":["trace[1110411399] 'read index received' (duration: 620.093016ms)","trace[1110411399] 'applied index is now lower than readState.Index' (duration: 67.941µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:46:49.359199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:48.69623Z","time spent":"662.960611ms","remote":"127.0.0.1:58972","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-02T08:46:49.359364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.340451ms","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-02T08:46:49.359595Z","caller":"traceutil/trace.go:171","msg":"trace[2020968689] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:586; }","duration":"620.618518ms","start":"2026-04-02T08:46:48.738954Z","end":"2026-04-02T08:46:49.359573Z","steps":["trace[2020968689] 'agreement among raft nodes before linearized reading' (duration: 620.327571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:49.359648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:48.738937Z","time spent":"620.696779ms","remote":"127.0.0.1:59058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-02T08:46:50.080674Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346108093638402098,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T08:46:50.426586Z","caller":"traceutil/trace.go:171","msg":"trace[574038524] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"846.634751ms","start":"2026-04-02T08:46:49.579912Z","end":"2026-04-02T08:46:50.426547Z","steps":["trace[574038524] 'read index received' (duration: 787.465758ms)","trace[574038524] 'applied index is now lower than readState.Index' (duration: 59.167493ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:50.426641Z","caller":"traceutil/trace.go:171","msg":"trace[1023504544] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"1.065770449s","start":"2026-04-02T08:46:49.360792Z","end":"2026-04-02T08:46:50.426562Z","steps":["trace[1023504544] 'process raft request' (duration: 1.006680017s)","trace[1023504544] 'compare' (duration: 58.868587ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:46:50.426835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.921398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:46:50.426895Z","caller":"traceutil/trace.go:171","msg":"trace[1717690711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"847.069811ms","start":"2026-04-02T08:46:49.579812Z","end":"2026-04-02T08:46:50.426882Z","steps":["trace[1717690711] 'agreement among raft nodes before linearized reading' (duration: 846.908737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:50.426935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:49.579782Z","time spent":"847.144933ms","remote":"127.0.0.1:46302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T08:46:50.426952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:49.360768Z","time spent":"1.066078706s","remote":"127.0.0.1:58972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:46:50.427011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.686891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-02T08:46:50.427037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.559598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-02T08:46:50.427053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.566129ms","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-02T08:46:50.427096Z","caller":"traceutil/trace.go:171","msg":"trace[1949238787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"814.68217ms","start":"2026-04-02T08:46:49.612399Z","end":"2026-04-02T08:46:50.427082Z","steps":["trace[1949238787] 'agreement among raft nodes before linearized reading' (duration: 814.454065ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:50.427109Z","caller":"traceutil/trace.go:171","msg":"trace[1746379063] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"761.653091ms","start":"2026-04-02T08:46:49.665439Z","end":"2026-04-02T08:46:50.427092Z","steps":["trace[1746379063] 'agreement among raft nodes before linearized reading' (duration: 761.480087ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:50.427089Z","caller":"traceutil/trace.go:171","msg":"trace[1077222160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"531.791304ms","start":"2026-04-02T08:46:49.895279Z","end":"2026-04-02T08:46:50.427071Z","steps":["trace[1077222160] 'agreement among raft nodes before linearized reading' (duration: 531.60503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:50.427149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:49.665418Z","time spent":"761.722772ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T08:46:50.427151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:49.612378Z","time spent":"814.761603ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T08:46:50.427187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:49.895264Z","time spent":"531.909836ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T08:46:50.427256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.747271ms","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":"warn","ts":"2026-04-02T08:46:50.42725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.737446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T08:46:50.427304Z","caller":"traceutil/trace.go:171","msg":"trace[948948065] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:587; }","duration":"306.833013ms","start":"2026-04-02T08:46:50.12046Z","end":"2026-04-02T08:46:50.427293Z","steps":["trace[948948065] 'agreement among raft nodes before linearized reading' (duration: 306.764122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:50.427341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:50.120443Z","time spent":"306.887104ms","remote":"127.0.0.1:59058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-04-02T08:46:50.42734Z","caller":"traceutil/trace.go:171","msg":"trace[1962429876] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"763.89214ms","start":"2026-04-02T08:46:49.663423Z","end":"2026-04-02T08:46:50.427315Z","steps":["trace[1962429876] 'agreement among raft nodes before linearized reading' (duration: 763.600233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:50.427412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:49.663401Z","time spent":"763.994132ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T08:46:51.226636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.669743ms","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-02T08:46:51.226779Z","caller":"traceutil/trace.go:171","msg":"trace[1693328826] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:588; }","duration":"490.862187ms","start":"2026-04-02T08:46:50.735897Z","end":"2026-04-02T08:46:51.226759Z","steps":["trace[1693328826] 'range keys from in-memory index tree' (duration: 490.647472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:51.227151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.486373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:46:51.227357Z","caller":"traceutil/trace.go:171","msg":"trace[250439370] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:614; }","duration":"706.827453ms","start":"2026-04-02T08:46:50.520492Z","end":"2026-04-02T08:46:51.227319Z","steps":["trace[250439370] 'read index received' (duration: 51.140855ms)","trace[250439370] 'applied index is now lower than readState.Index' (duration: 655.685777ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:51.227423Z","caller":"traceutil/trace.go:171","msg":"trace[54165978] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"795.283147ms","start":"2026-04-02T08:46:50.432131Z","end":"2026-04-02T08:46:51.227414Z","steps":["trace[54165978] 'process raft request' (duration: 795.095612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:51.227479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:50.432123Z","time spent":"795.322627ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:46:51.227509Z","caller":"traceutil/trace.go:171","msg":"trace[393468891] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"794.98141ms","start":"2026-04-02T08:46:50.432486Z","end":"2026-04-02T08:46:51.227467Z","steps":["trace[393468891] 'process raft request' (duration: 794.797016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:51.227626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.129249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-02T08:46:51.227663Z","caller":"traceutil/trace.go:171","msg":"trace[60716288] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:591; }","duration":"707.16244ms","start":"2026-04-02T08:46:50.52049Z","end":"2026-04-02T08:46:51.227652Z","steps":["trace[60716288] 'agreement among raft nodes before linearized reading' (duration: 707.100309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:51.227693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:50.520459Z","time spent":"707.227552ms","remote":"127.0.0.1:59244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-04-02T08:46:51.227678Z","caller":"traceutil/trace.go:171","msg":"trace[2132813164] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"795.762558ms","start":"2026-04-02T08:46:50.431886Z","end":"2026-04-02T08:46:51.227649Z","steps":["trace[2132813164] 'process raft request' (duration: 139.706312ms)","trace[2132813164] 'compare' (duration: 655.389291ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:46:51.227722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:50.432476Z","time spent":"795.127914ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:46:51.227802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:50.431878Z","time spent":"795.85866ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:46:51.727784Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346108093638402120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:46:52.228887Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346108093638402120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:46:52.579585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000474257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-02T08:46:52.579661Z","caller":"traceutil/trace.go:171","msg":"trace[1147459652] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00059491s","start":"2026-04-02T08:46:50.579048Z","end":"2026-04-02T08:46:52.579642Z","steps":["trace[1147459652] 'agreement among raft nodes before linearized reading' (duration: 2.000500958s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:52.579751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:50.579029Z","time spent":"2.000710322s","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/02 08:46:52 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-02T08:46:52.729672Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346108093638402120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:46:53.230673Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346108093638402120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:46:53.561944Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.248735898s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T08:46:53.562274Z","caller":"traceutil/trace.go:171","msg":"trace[1057981496] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"2.935273664s","start":"2026-04-02T08:46:50.626977Z","end":"2026-04-02T08:46:53.562251Z","steps":["trace[1057981496] 'process raft request' (duration: 2.935150012s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:53.562403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:50.626955Z","time spent":"2.935388176s","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:46:53.583709Z","caller":"traceutil/trace.go:171","msg":"trace[883262473] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"2.954059057s","start":"2026-04-02T08:46:50.629618Z","end":"2026-04-02T08:46:53.583677Z","steps":["trace[883262473] 'process raft request' (duration: 2.953786621s)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.583786Z","caller":"traceutil/trace.go:171","msg":"trace[986746016] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"2.953381392s","start":"2026-04-02T08:46:50.630376Z","end":"2026-04-02T08:46:53.583757Z","steps":["trace[986746016] 'process raft request' (duration: 2.953163687s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:53.583857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:50.629609Z","time spent":"2.95417672s","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T08:46:53.583919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:50.630357Z","time spent":"2.953495815s","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:46:53.58388Z","caller":"traceutil/trace.go:171","msg":"trace[1517524900] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"2.948132886s","start":"2026-04-02T08:46:50.635726Z","end":"2026-04-02T08:46:53.583859Z","steps":["trace[1517524900] 'process raft request' (duration: 2.947867841s)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.584021Z","caller":"traceutil/trace.go:171","msg":"trace[364602636] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"2.943219157s","start":"2026-04-02T08:46:50.640776Z","end":"2026-04-02T08:46:53.583995Z","steps":["trace[364602636] 'process raft request' (duration: 2.943034882s)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.584048Z","caller":"traceutil/trace.go:171","msg":"trace[1131882362] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"2.943000391s","start":"2026-04-02T08:46:50.641039Z","end":"2026-04-02T08:46:53.58404Z","steps":["trace[1131882362] 'process raft request' (duration: 2.942842158s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:53.584084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:50.640524Z","time spent":"2.943541784s","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T08:46:53.584091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:50.640757Z","time spent":"2.943300939s","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T08:46:53.584067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:50.635713Z","time spent":"2.948261149s","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:46:53.584401Z","caller":"traceutil/trace.go:171","msg":"trace[1904352259] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"2.941169421s","start":"2026-04-02T08:46:50.643003Z","end":"2026-04-02T08:46:53.584173Z","steps":["trace[1904352259] 'process raft request' (duration: 2.940960927s)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.58448Z","caller":"traceutil/trace.go:171","msg":"trace[1632088496] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"2.93393452s","start":"2026-04-02T08:46:50.650535Z","end":"2026-04-02T08:46:53.58447Z","steps":["trace[1632088496] 'process raft request' (duration: 2.933517511s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:53.58455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:50.642999Z","time spent":"2.941508679s","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T08:46:53.58455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:50.650515Z","time spent":"2.934001092s","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:46:53.584573Z","caller":"traceutil/trace.go:171","msg":"trace[1401101997] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"2.916570575s","start":"2026-04-02T08:46:50.667994Z","end":"2026-04-02T08:46:53.584564Z","steps":["trace[1401101997] 'process raft request' (duration: 2.916111335s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:53.584615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:50.667954Z","time spent":"2.916644607s","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:46:53.584424Z","caller":"traceutil/trace.go:171","msg":"trace[1554911556] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"2.941447228s","start":"2026-04-02T08:46:50.642962Z","end":"2026-04-02T08:46:53.58441Z","steps":["trace[1554911556] 'process raft request' (duration: 2.940960997s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:53.58466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:50.642955Z","time spent":"2.941685003s","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:46:53.584662Z","caller":"traceutil/trace.go:171","msg":"trace[44909451] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"2.793505072s","start":"2026-04-02T08:46:50.791144Z","end":"2026-04-02T08:46:53.584649Z","steps":["trace[44909451] 'process raft request' (duration: 2.793387589s)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.584668Z","caller":"traceutil/trace.go:171","msg":"trace[1504256570] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:618; }","duration":"2.357259218s","start":"2026-04-02T08:46:51.227388Z","end":"2026-04-02T08:46:53.584647Z","steps":["trace[1504256570] 'read index received' (duration: 2.337450642s)","trace[1504256570] 'applied index is now lower than readState.Index' (duration: 19.807876ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:46:53.584726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:50.791105Z","time spent":"2.793588494s","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:46:53.584739Z","caller":"traceutil/trace.go:171","msg":"trace[517178548] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.466644495s","start":"2026-04-02T08:46:52.118084Z","end":"2026-04-02T08:46:53.584729Z","steps":["trace[517178548] 'process raft request' (duration: 1.466507262s)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.5848Z","caller":"traceutil/trace.go:171","msg":"trace[1930688458] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"2.88890009s","start":"2026-04-02T08:46:50.695893Z","end":"2026-04-02T08:46:53.584793Z","steps":["trace[1930688458] 'process raft request' (duration: 2.888529092s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:53.584808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:52.11806Z","time spent":"1.466699666s","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:46:53.584834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:50.695866Z","time spent":"2.888953322s","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T08:46:53.64144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.220600236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T08:46:53.641493Z","caller":"traceutil/trace.go:171","msg":"trace[1504448443] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:606; }","duration":"2.220718178s","start":"2026-04-02T08:46:51.420764Z","end":"2026-04-02T08:46:53.641482Z","steps":["trace[1504448443] 'agreement among raft nodes before linearized reading' (duration: 2.220605136s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:53.641518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:51.420741Z","time spent":"2.22077097s","remote":"127.0.0.1:59142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-04-02T08:46:53.641618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.999359ms","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-02T08:46:53.641635Z","caller":"traceutil/trace.go:171","msg":"trace[2031341345] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:606; }","duration":"409.04943ms","start":"2026-04-02T08:46:53.23258Z","end":"2026-04-02T08:46:53.641629Z","steps":["trace[2031341345] 'agreement among raft nodes before linearized reading' (duration: 409.016819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:53.641652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:53.23256Z","time spent":"409.086031ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T08:46:53.64174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.230674ms","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-02T08:46:53.641755Z","caller":"traceutil/trace.go:171","msg":"trace[538924961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:606; }","duration":"409.269845ms","start":"2026-04-02T08:46:53.23248Z","end":"2026-04-02T08:46:53.64175Z","steps":["trace[538924961] 'agreement among raft nodes before linearized reading' (duration: 409.241025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:53.64177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:53.232463Z","time spent":"409.302676ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T08:46:53.641979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.50254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T08:46:53.641997Z","caller":"traceutil/trace.go:171","msg":"trace[1941945554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:606; }","duration":"409.555742ms","start":"2026-04-02T08:46:53.232436Z","end":"2026-04-02T08:46:53.641992Z","steps":["trace[1941945554] 'agreement among raft nodes before linearized reading' (duration: 409.519411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:53.642015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:53.232415Z","time spent":"409.596302ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T08:46:53.642091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.181493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:46:53.642106Z","caller":"traceutil/trace.go:171","msg":"trace[35907792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"609.237734ms","start":"2026-04-02T08:46:53.032863Z","end":"2026-04-02T08:46:53.642101Z","steps":["trace[35907792] 'agreement among raft nodes before linearized reading' (duration: 609.213233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:53.64212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:53.032841Z","time spent":"609.275785ms","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T08:46:53.642196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062364397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:46:53.64221Z","caller":"traceutil/trace.go:171","msg":"trace[1978941929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"1.062419938s","start":"2026-04-02T08:46:52.579785Z","end":"2026-04-02T08:46:53.642205Z","steps":["trace[1978941929] 'agreement among raft nodes before linearized reading' (duration: 1.062399048s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:53.642224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:52.579761Z","time spent":"1.062459729s","remote":"127.0.0.1:46302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T08:46:53.642306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.118710482s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T08:46:53.642321Z","caller":"traceutil/trace.go:171","msg":"trace[1884683324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"1.118764494s","start":"2026-04-02T08:46:52.523552Z","end":"2026-04-02T08:46:53.642317Z","steps":["trace[1884683324] 'agreement among raft nodes before linearized reading' (duration: 1.118735343s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:53.642336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:52.523534Z","time spent":"1.118798384s","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T08:46:53.64242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.695371301s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T08:46:53.642437Z","caller":"traceutil/trace.go:171","msg":"trace[652794248] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:606; }","duration":"1.695427722s","start":"2026-04-02T08:46:51.947004Z","end":"2026-04-02T08:46:53.642432Z","steps":["trace[652794248] 'agreement among raft nodes before linearized reading' (duration: 1.695390691s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:53.642452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:46:51.946983Z","time spent":"1.695464463s","remote":"127.0.0.1:59288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-04-02T08:46:53.785996Z","caller":"traceutil/trace.go:171","msg":"trace[568361648] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"142.898772ms","start":"2026-04-02T08:46:53.64307Z","end":"2026-04-02T08:46:53.785968Z","steps":["trace[568361648] 'process raft request' (duration: 135.696423ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.786176Z","caller":"traceutil/trace.go:171","msg":"trace[611330487] transaction","detail":"{read_only:false; number_of_response:0; response_revision:613; }","duration":"136.451229ms","start":"2026-04-02T08:46:53.649717Z","end":"2026-04-02T08:46:53.786169Z","steps":["trace[611330487] 'process raft request' (duration: 136.294506ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.786346Z","caller":"traceutil/trace.go:171","msg":"trace[1478324921] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"136.572831ms","start":"2026-04-02T08:46:53.649748Z","end":"2026-04-02T08:46:53.786321Z","steps":["trace[1478324921] 'process raft request' (duration: 136.306106ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.786422Z","caller":"traceutil/trace.go:171","msg":"trace[1088904090] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"142.214636ms","start":"2026-04-02T08:46:53.644198Z","end":"2026-04-02T08:46:53.786412Z","steps":["trace[1088904090] 'process raft request' (duration: 141.675275ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.786571Z","caller":"traceutil/trace.go:171","msg":"trace[1875407645] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"136.682554ms","start":"2026-04-02T08:46:53.649861Z","end":"2026-04-02T08:46:53.786543Z","steps":["trace[1875407645] 'process raft request' (duration: 136.585652ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.786705Z","caller":"traceutil/trace.go:171","msg":"trace[1545938675] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"136.893319ms","start":"2026-04-02T08:46:53.649803Z","end":"2026-04-02T08:46:53.786696Z","steps":["trace[1545938675] 'process raft request' (duration: 136.374327ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.786892Z","caller":"traceutil/trace.go:171","msg":"trace[807810482] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"137.117794ms","start":"2026-04-02T08:46:53.649766Z","end":"2026-04-02T08:46:53.786883Z","steps":["trace[807810482] 'process raft request' (duration: 136.360227ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.78691Z","caller":"traceutil/trace.go:171","msg":"trace[1027473008] transaction","detail":"{read_only:false; number_of_response:0; response_revision:619; }","duration":"116.798566ms","start":"2026-04-02T08:46:53.670104Z","end":"2026-04-02T08:46:53.786902Z","steps":["trace[1027473008] 'process raft request' (duration: 116.697824ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.786995Z","caller":"traceutil/trace.go:171","msg":"trace[1495378511] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"137.060233ms","start":"2026-04-02T08:46:53.649825Z","end":"2026-04-02T08:46:53.786885Z","steps":["trace[1495378511] 'process raft request' (duration: 136.566072ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.787055Z","caller":"traceutil/trace.go:171","msg":"trace[473704751] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"137.121854ms","start":"2026-04-02T08:46:53.649927Z","end":"2026-04-02T08:46:53.787049Z","steps":["trace[473704751] 'process raft request' (duration: 136.731955ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.787082Z","caller":"traceutil/trace.go:171","msg":"trace[1442571066] transaction","detail":"{read_only:false; number_of_response:0; response_revision:619; }","duration":"134.181329ms","start":"2026-04-02T08:46:53.652884Z","end":"2026-04-02T08:46:53.787065Z","steps":["trace[1442571066] 'process raft request' (duration: 133.896433ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.787219Z","caller":"traceutil/trace.go:171","msg":"trace[1228506596] transaction","detail":"{read_only:false; number_of_response:0; response_revision:619; }","duration":"117.055341ms","start":"2026-04-02T08:46:53.670156Z","end":"2026-04-02T08:46:53.787211Z","steps":["trace[1228506596] 'process raft request' (duration: 116.841436ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.787032Z","caller":"traceutil/trace.go:171","msg":"trace[1644794619] transaction","detail":"{read_only:false; number_of_response:1; response_revision:618; }","duration":"137.122414ms","start":"2026-04-02T08:46:53.649901Z","end":"2026-04-02T08:46:53.787023Z","steps":["trace[1644794619] 'process raft request' (duration: 136.597492ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.787315Z","caller":"traceutil/trace.go:171","msg":"trace[406259025] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"110.501077ms","start":"2026-04-02T08:46:53.676796Z","end":"2026-04-02T08:46:53.787297Z","steps":["trace[406259025] 'process raft request' (duration: 110.256502ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.793114Z","caller":"traceutil/trace.go:171","msg":"trace[8874576] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:638; }","duration":"141.849138ms","start":"2026-04-02T08:46:53.651211Z","end":"2026-04-02T08:46:53.79306Z","steps":["trace[8874576] 'read index received' (duration: 127.482041ms)","trace[8874576] 'applied index is now lower than readState.Index' (duration: 14.234834ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:53.793326Z","caller":"traceutil/trace.go:171","msg":"trace[9558507] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"103.568034ms","start":"2026-04-02T08:46:53.683722Z","end":"2026-04-02T08:46:53.78729Z","steps":["trace[9558507] 'process raft request' (duration: 103.433341ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:53.793504Z","caller":"traceutil/trace.go:171","msg":"trace[125253221] transaction","detail":"{read_only:false; number_of_response:0; response_revision:619; }","duration":"117.145593ms","start":"2026-04-02T08:46:53.670163Z","end":"2026-04-02T08:46:53.787308Z","steps":["trace[125253221] 'process raft request' (duration: 116.719654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:53.793762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.537593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:46:53.793824Z","caller":"traceutil/trace.go:171","msg":"trace[1696499933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"142.608545ms","start":"2026-04-02T08:46:53.651194Z","end":"2026-04-02T08:46:53.793802Z","steps":["trace[1696499933] 'agreement among raft nodes before linearized reading' (duration: 142.522893ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:54.033618Z","caller":"traceutil/trace.go:171","msg":"trace[2047135767] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"190.805087ms","start":"2026-04-02T08:46:53.842796Z","end":"2026-04-02T08:46:54.033601Z","steps":["trace[2047135767] 'process raft request' (duration: 105.867025ms)","trace[2047135767] 'compare' (duration: 84.782678ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:54.034035Z","caller":"traceutil/trace.go:171","msg":"trace[437653107] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"185.668143ms","start":"2026-04-02T08:46:53.848356Z","end":"2026-04-02T08:46:54.034025Z","steps":["trace[437653107] 'process raft request' (duration: 185.209713ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:54.034232Z","caller":"traceutil/trace.go:171","msg":"trace[1085043306] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"183.365602ms","start":"2026-04-02T08:46:53.850837Z","end":"2026-04-02T08:46:54.034202Z","steps":["trace[1085043306] 'process raft request' (duration: 183.096706ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:54.043125Z","caller":"traceutil/trace.go:171","msg":"trace[1615608256] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"181.553962ms","start":"2026-04-02T08:46:53.861548Z","end":"2026-04-02T08:46:54.043102Z","steps":["trace[1615608256] 'process raft request' (duration: 181.445389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:46:54.306466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.889678ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-02T08:46:54.306919Z","caller":"traceutil/trace.go:171","msg":"trace[732916716] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"261.002253ms","start":"2026-04-02T08:46:54.0459Z","end":"2026-04-02T08:46:54.306902Z","steps":["trace[732916716] 'process raft request' (duration: 260.825119ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:54.307183Z","caller":"traceutil/trace.go:171","msg":"trace[1002076374] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"263.568169ms","start":"2026-04-02T08:46:54.043604Z","end":"2026-04-02T08:46:54.307172Z","steps":["trace[1002076374] 'process raft request' (duration: 262.963625ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:54.307227Z","caller":"traceutil/trace.go:171","msg":"trace[817118316] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"210.640924ms","start":"2026-04-02T08:46:54.096546Z","end":"2026-04-02T08:46:54.307187Z","steps":["trace[817118316] 'process raft request' (duration: 210.247905ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:54.444617Z","caller":"traceutil/trace.go:171","msg":"trace[337255691] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"113.517758ms","start":"2026-04-02T08:46:54.331072Z","end":"2026-04-02T08:46:54.44459Z","steps":["trace[337255691] 'process raft request' (duration: 74.862708ms)","trace[337255691] 'compare' (duration: 38.389264ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:54.602308Z","caller":"traceutil/trace.go:171","msg":"trace[1825828729] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"129.874248ms","start":"2026-04-02T08:46:54.472409Z","end":"2026-04-02T08:46:54.602283Z","steps":["trace[1825828729] 'process raft request' (duration: 129.813776ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:54.602511Z","caller":"traceutil/trace.go:171","msg":"trace[846758231] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"140.253986ms","start":"2026-04-02T08:46:54.462243Z","end":"2026-04-02T08:46:54.602497Z","steps":["trace[846758231] 'process raft request' (duration: 139.693504ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:54.793972Z","caller":"traceutil/trace.go:171","msg":"trace[1261668686] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"143.856735ms","start":"2026-04-02T08:46:54.650082Z","end":"2026-04-02T08:46:54.793939Z","steps":["trace[1261668686] 'process raft request' (duration: 56.35775ms)","trace[1261668686] 'compare' (duration: 87.346892ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:54.99274Z","caller":"traceutil/trace.go:171","msg":"trace[1577070261] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"161.659606ms","start":"2026-04-02T08:46:54.831062Z","end":"2026-04-02T08:46:54.992722Z","steps":["trace[1577070261] 'process raft request' (duration: 75.560302ms)","trace[1577070261] 'compare' (duration: 86.001712ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:54.994187Z","caller":"traceutil/trace.go:171","msg":"trace[1145309976] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"103.394795ms","start":"2026-04-02T08:46:54.890764Z","end":"2026-04-02T08:46:54.994159Z","steps":["trace[1145309976] 'process raft request' (duration: 102.835553ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:55.159612Z","caller":"traceutil/trace.go:171","msg":"trace[1653607522] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"114.707448ms","start":"2026-04-02T08:46:55.044869Z","end":"2026-04-02T08:46:55.159576Z","steps":["trace[1653607522] 'process raft request' (duration: 55.367276ms)","trace[1653607522] 'compare' (duration: 59.156688ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:55.248377Z","caller":"traceutil/trace.go:171","msg":"trace[2008528651] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"160.277328ms","start":"2026-04-02T08:46:55.088082Z","end":"2026-04-02T08:46:55.248359Z","steps":["trace[2008528651] 'process raft request' (duration: 160.184226ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:55.302921Z","caller":"traceutil/trace.go:171","msg":"trace[1776418073] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"185.610264ms","start":"2026-04-02T08:46:55.117281Z","end":"2026-04-02T08:46:55.302891Z","steps":["trace[1776418073] 'process raft request' (duration: 161.883634ms)","trace[1776418073] 'compare' (duration: 21.403929ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:55.303397Z","caller":"traceutil/trace.go:171","msg":"trace[1623432764] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"136.311193ms","start":"2026-04-02T08:46:55.167043Z","end":"2026-04-02T08:46:55.303354Z","steps":["trace[1623432764] 'process raft request' (duration: 133.687975ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:55.488706Z","caller":"traceutil/trace.go:171","msg":"trace[867292673] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"165.79315ms","start":"2026-04-02T08:46:55.322889Z","end":"2026-04-02T08:46:55.488682Z","steps":["trace[867292673] 'process raft request' (duration: 78.932913ms)","trace[867292673] 'compare' (duration: 86.306235ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:55.488943Z","caller":"traceutil/trace.go:171","msg":"trace[1136804280] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"155.016063ms","start":"2026-04-02T08:46:55.33391Z","end":"2026-04-02T08:46:55.488927Z","steps":["trace[1136804280] 'process raft request' (duration: 154.413379ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:46:55.789499Z","caller":"traceutil/trace.go:171","msg":"trace[106512624] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"143.741135ms","start":"2026-04-02T08:46:55.645739Z","end":"2026-04-02T08:46:55.78948Z","steps":["trace[106512624] 'process raft request' (duration: 97.156123ms)","trace[106512624] 'compare' (duration: 46.097731ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:46:56.485555Z","caller":"traceutil/trace.go:171","msg":"trace[613642884] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"123.085526ms","start":"2026-04-02T08:46:56.362452Z","end":"2026-04-02T08:46:56.485538Z","steps":["trace[613642884] 'process raft request' (duration: 118.910825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:01.23135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.927717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:47:01.231448Z","caller":"traceutil/trace.go:171","msg":"trace[1715039358] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"257.355109ms","start":"2026-04-02T08:47:00.974075Z","end":"2026-04-02T08:47:01.23143Z","steps":["trace[1715039358] 'process raft request' (duration: 91.28724ms)","trace[1715039358] 'compare' (duration: 165.791413ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:47:01.702831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.64888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:47:01.702889Z","caller":"traceutil/trace.go:171","msg":"trace[881402203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:695; }","duration":"123.760973ms","start":"2026-04-02T08:47:01.579114Z","end":"2026-04-02T08:47:01.702875Z","steps":["trace[881402203] 'range keys from in-memory index tree' (duration: 123.552718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:02.418888Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346108093638402377,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:47:02.692276Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.01085305s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T08:47:02.693413Z","caller":"traceutil/trace.go:171","msg":"trace[557985708] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:731; }","duration":"774.62549ms","start":"2026-04-02T08:47:01.918763Z","end":"2026-04-02T08:47:02.693388Z","steps":["trace[557985708] 'read index received' (duration: 773.634399ms)","trace[557985708] 'applied index is now lower than readState.Index' (duration: 990.051µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:47:02.693625Z","caller":"traceutil/trace.go:171","msg":"trace[742329775] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"1.013210911s","start":"2026-04-02T08:47:01.680319Z","end":"2026-04-02T08:47:02.69353Z","steps":["trace[742329775] 'process raft request' (duration: 1.012446915s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:02.693809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:01.680303Z","time spent":"1.013377825s","remote":"127.0.0.1:59058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:47:02.694011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.10144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T08:47:02.694082Z","caller":"traceutil/trace.go:171","msg":"trace[1336723639] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:696; }","duration":"763.249883ms","start":"2026-04-02T08:47:01.930809Z","end":"2026-04-02T08:47:02.694059Z","steps":["trace[1336723639] 'agreement among raft nodes before linearized reading' (duration: 763.12434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:02.694132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:01.93057Z","time spent":"763.55167ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T08:47:02.694262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.064818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sd4nl\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-02T08:47:02.694316Z","caller":"traceutil/trace.go:171","msg":"trace[406920927] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sd4nl; range_end:; response_count:1; response_revision:696; }","duration":"359.16244ms","start":"2026-04-02T08:47:02.335142Z","end":"2026-04-02T08:47:02.694304Z","steps":["trace[406920927] 'agreement among raft nodes before linearized reading' (duration: 359.019927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:02.694352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:02.335125Z","time spent":"359.219811ms","remote":"127.0.0.1:59142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-sd4nl\" "} {"level":"warn","ts":"2026-04-02T08:47:02.694398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.884447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T08:47:02.694421Z","caller":"traceutil/trace.go:171","msg":"trace[1611897847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:696; }","duration":"757.930868ms","start":"2026-04-02T08:47:01.936483Z","end":"2026-04-02T08:47:02.694414Z","steps":["trace[1611897847] 'agreement among raft nodes before linearized reading' (duration: 757.851387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:02.694445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:01.936469Z","time spent":"757.971889ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T08:47:02.694553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.347617ms","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-02T08:47:02.694575Z","caller":"traceutil/trace.go:171","msg":"trace[1787730936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:696; }","duration":"758.401499ms","start":"2026-04-02T08:47:01.936168Z","end":"2026-04-02T08:47:02.69457Z","steps":["trace[1787730936] 'agreement among raft nodes before linearized reading' (duration: 758.348188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:02.694591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:01.936147Z","time spent":"758.440379ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T08:47:02.694639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.877047ms","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-02T08:47:02.694801Z","caller":"traceutil/trace.go:171","msg":"trace[506264433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:696; }","duration":"776.071421ms","start":"2026-04-02T08:47:01.918715Z","end":"2026-04-02T08:47:02.694787Z","steps":["trace[506264433] 'agreement among raft nodes before linearized reading' (duration: 775.865837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:02.694861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.014993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:47:02.694888Z","caller":"traceutil/trace.go:171","msg":"trace[881316661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:696; }","duration":"118.077264ms","start":"2026-04-02T08:47:02.576804Z","end":"2026-04-02T08:47:02.694882Z","steps":["trace[881316661] 'agreement among raft nodes before linearized reading' (duration: 118.039454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:02.694924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:01.918697Z","time spent":"776.172743ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T08:47:03.527021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.462435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:47:03.527105Z","caller":"traceutil/trace.go:171","msg":"trace[644632096] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"827.152ms","start":"2026-04-02T08:47:02.699939Z","end":"2026-04-02T08:47:03.527091Z","steps":["trace[644632096] 'process raft request' (duration: 552.553362ms)","trace[644632096] 'compare' (duration: 274.358613ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:47:03.527148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:02.69992Z","time spent":"827.208182ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:47:03.75407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.893921ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:47:03.754308Z","caller":"traceutil/trace.go:171","msg":"trace[146979093] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"1.054379976s","start":"2026-04-02T08:47:02.699916Z","end":"2026-04-02T08:47:03.754296Z","steps":["trace[146979093] 'process raft request' (duration: 951.200409ms)","trace[146979093] 'compare' (duration: 102.809059ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:47:03.754359Z","caller":"traceutil/trace.go:171","msg":"trace[930146998] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"1.053046387s","start":"2026-04-02T08:47:02.701295Z","end":"2026-04-02T08:47:03.754341Z","steps":["trace[930146998] 'process raft request' (duration: 1.052931695s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:03.754423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:02.69989Z","time spent":"1.054482908s","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:47:03.754521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:02.701275Z","time spent":"1.053148499s","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:47:03.754516Z","caller":"traceutil/trace.go:171","msg":"trace[1838192267] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"1.054147131s","start":"2026-04-02T08:47:02.700183Z","end":"2026-04-02T08:47:03.75433Z","steps":["trace[1838192267] 'process raft request' (duration: 1.054005538s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:03.754716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:02.70017Z","time spent":"1.054458988s","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:47:03.75483Z","caller":"traceutil/trace.go:171","msg":"trace[233226946] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:733; }","duration":"233.41631ms","start":"2026-04-02T08:47:03.521404Z","end":"2026-04-02T08:47:03.75482Z","steps":["trace[233226946] 'read index received' (duration: 129.70023ms)","trace[233226946] 'applied index is now lower than readState.Index' (duration: 103.71366ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:47:03.75482Z","caller":"traceutil/trace.go:171","msg":"trace[1689911312] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"1.006365787s","start":"2026-04-02T08:47:02.748431Z","end":"2026-04-02T08:47:03.754796Z","steps":["trace[1689911312] 'process raft request' (duration: 1.005830766s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:03.754963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:02.748406Z","time spent":"1.00649015s","remote":"127.0.0.1:59142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:47:03.754983Z","caller":"traceutil/trace.go:171","msg":"trace[1278007986] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"993.207363ms","start":"2026-04-02T08:47:02.761768Z","end":"2026-04-02T08:47:03.754975Z","steps":["trace[1278007986] 'process raft request' (duration: 992.641011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:03.755068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:02.761742Z","time spent":"993.305195ms","remote":"127.0.0.1:59284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T08:47:03.755104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.695476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T08:47:03.755145Z","caller":"traceutil/trace.go:171","msg":"trace[1700407530] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:702; }","duration":"233.770916ms","start":"2026-04-02T08:47:03.521362Z","end":"2026-04-02T08:47:03.755133Z","steps":["trace[1700407530] 'agreement among raft nodes before linearized reading' (duration: 233.711875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:05.577493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000423837s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-02T08:47:05.577578Z","caller":"traceutil/trace.go:171","msg":"trace[179699606] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000549309s","start":"2026-04-02T08:47:03.577009Z","end":"2026-04-02T08:47:05.577558Z","steps":["trace[179699606] 'agreement among raft nodes before linearized reading' (duration: 2.000444007s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:05.577659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:03.576993Z","time spent":"2.000645962s","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T08:47:06.299196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:04.812056Z","time spent":"1.487131665s","remote":"127.0.0.1:47164","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-02T08:47:06.299715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.370092854s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:47:06.299791Z","caller":"traceutil/trace.go:171","msg":"trace[791701643] linearizableReadLoop","detail":"{readStateIndex:739; appliedIndex:738; }","duration":"2.544905177s","start":"2026-04-02T08:47:03.754872Z","end":"2026-04-02T08:47:06.299777Z","steps":["trace[791701643] 'read index received' (duration: 174.550208ms)","trace[791701643] 'applied index is now lower than readState.Index' (duration: 2.370353919s)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:47:06.299957Z","caller":"traceutil/trace.go:171","msg":"trace[275575100] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"2.566521845s","start":"2026-04-02T08:47:03.733416Z","end":"2026-04-02T08:47:06.299938Z","steps":["trace[275575100] 'process raft request' (duration: 196.127265ms)","trace[275575100] 'compare' (duration: 2.369769406s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:47:06.300079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:03.733394Z","time spent":"2.566611116s","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:47:06.800631Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346108093638402391,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:47:06.961033Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.403565416s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-02T08:47:07.135054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.798985ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-04-02T08:47:07.135225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.049681214s","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-02T08:47:07.135261Z","caller":"traceutil/trace.go:171","msg":"trace[1246784519] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:703; }","duration":"2.049763496s","start":"2026-04-02T08:47:05.085487Z","end":"2026-04-02T08:47:07.13525Z","steps":["trace[1246784519] 'agreement among raft nodes before linearized reading' (duration: 2.049693545s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:07.135294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:05.085452Z","time spent":"2.049834098s","remote":"127.0.0.1:59442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-04-02T08:47:07.135375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.778041255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T08:47:07.135353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.557133753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:47:07.135571Z","caller":"traceutil/trace.go:171","msg":"trace[910776573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:703; }","duration":"1.557388498s","start":"2026-04-02T08:47:05.578164Z","end":"2026-04-02T08:47:07.135553Z","steps":["trace[910776573] 'agreement among raft nodes before linearized reading' (duration: 1.557001169s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:07.13559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.344416247s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T08:47:07.135616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:05.578151Z","time spent":"1.557455999s","remote":"127.0.0.1:46302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T08:47:07.135413Z","caller":"traceutil/trace.go:171","msg":"trace[426949079] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:703; }","duration":"2.778117767s","start":"2026-04-02T08:47:04.357287Z","end":"2026-04-02T08:47:07.135404Z","steps":["trace[426949079] 'agreement among raft nodes before linearized reading' (duration: 2.778038505s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:07.135659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:04.357269Z","time spent":"2.778381432s","remote":"127.0.0.1:59152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-02T08:47:07.135677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37605816s","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-02T08:47:07.135731Z","caller":"traceutil/trace.go:171","msg":"trace[627230034] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:703; }","duration":"1.376141871s","start":"2026-04-02T08:47:05.759557Z","end":"2026-04-02T08:47:07.135699Z","steps":["trace[627230034] 'agreement among raft nodes before linearized reading' (duration: 1.375682091s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:07.13576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:05.759543Z","time spent":"1.376211353s","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T08:47:07.135765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.376461928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-02T08:47:07.135451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37606213s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-02T08:47:07.135901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.584631033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T08:47:07.135919Z","caller":"traceutil/trace.go:171","msg":"trace[670225298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:703; }","duration":"1.376496079s","start":"2026-04-02T08:47:05.759333Z","end":"2026-04-02T08:47:07.13583Z","steps":["trace[670225298] 'agreement among raft nodes before linearized reading' (duration: 1.375934747s)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:07.13593Z","caller":"traceutil/trace.go:171","msg":"trace[1572365537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:703; }","duration":"1.584661534s","start":"2026-04-02T08:47:05.551262Z","end":"2026-04-02T08:47:07.135923Z","steps":["trace[1572365537] 'agreement among raft nodes before linearized reading' (duration: 1.583956009s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:07.135474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.932599765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T08:47:07.136001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:05.551203Z","time spent":"1.584789937s","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T08:47:07.136016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:05.759329Z","time spent":"1.376621321s","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T08:47:07.136022Z","caller":"traceutil/trace.go:171","msg":"trace[530888721] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:703; }","duration":"2.933178648s","start":"2026-04-02T08:47:04.202835Z","end":"2026-04-02T08:47:07.136014Z","steps":["trace[530888721] 'agreement among raft nodes before linearized reading' (duration: 2.932618786s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:07.136117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:04.202818Z","time spent":"2.933249049s","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-04-02T08:47:07.135808Z","caller":"traceutil/trace.go:171","msg":"trace[1654589320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:703; }","duration":"1.376524849s","start":"2026-04-02T08:47:05.759269Z","end":"2026-04-02T08:47:07.135794Z","steps":["trace[1654589320] 'agreement among raft nodes before linearized reading' (duration: 1.376013088s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:07.136172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:05.759251Z","time spent":"1.376913608s","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T08:47:07.135152Z","caller":"traceutil/trace.go:171","msg":"trace[476521095] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:739; }","duration":"835.323293ms","start":"2026-04-02T08:47:06.299814Z","end":"2026-04-02T08:47:07.135137Z","steps":["trace[476521095] 'read index received' (duration: 661.361765ms)","trace[476521095] 'applied index is now lower than readState.Index' (duration: 173.958378ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:47:07.136328Z","caller":"traceutil/trace.go:171","msg":"trace[1663370987] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:703; }","duration":"3.3450222s","start":"2026-04-02T08:47:03.791141Z","end":"2026-04-02T08:47:07.136163Z","steps":["trace[1663370987] 'agreement among raft nodes before linearized reading' (duration: 3.344389086s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:07.13637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:03.791108Z","time spent":"3.345253195s","remote":"127.0.0.1:59288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-02T08:47:07.286028Z","caller":"traceutil/trace.go:171","msg":"trace[1985580772] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"142.919202ms","start":"2026-04-02T08:47:07.143084Z","end":"2026-04-02T08:47:07.286004Z","steps":["trace[1985580772] 'process raft request' (duration: 142.694827ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:07.28646Z","caller":"traceutil/trace.go:171","msg":"trace[739394254] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"140.47994ms","start":"2026-04-02T08:47:07.145968Z","end":"2026-04-02T08:47:07.286448Z","steps":["trace[739394254] 'process raft request' (duration: 140.198204ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:07.286456Z","caller":"traceutil/trace.go:171","msg":"trace[1467751356] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:740; }","duration":"141.45951ms","start":"2026-04-02T08:47:07.144974Z","end":"2026-04-02T08:47:07.286434Z","steps":["trace[1467751356] 'read index received' (duration: 92.753047ms)","trace[1467751356] 'applied index is now lower than readState.Index' (duration: 48.705593ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:47:07.286646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.615454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:47:07.286684Z","caller":"traceutil/trace.go:171","msg":"trace[885566122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:708; }","duration":"141.716736ms","start":"2026-04-02T08:47:07.144956Z","end":"2026-04-02T08:47:07.286673Z","steps":["trace[885566122] 'agreement among raft nodes before linearized reading' (duration: 141.570332ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:07.286766Z","caller":"traceutil/trace.go:171","msg":"trace[28510034] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"109.881363ms","start":"2026-04-02T08:47:07.176874Z","end":"2026-04-02T08:47:07.286756Z","steps":["trace[28510034] 'process raft request' (duration: 109.496285ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:07.287396Z","caller":"traceutil/trace.go:171","msg":"trace[894526809] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"141.132453ms","start":"2026-04-02T08:47:07.145144Z","end":"2026-04-02T08:47:07.286276Z","steps":["trace[894526809] 'process raft request' (duration: 140.815426ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:07.724202Z","caller":"traceutil/trace.go:171","msg":"trace[716173734] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"115.920663ms","start":"2026-04-02T08:47:07.608253Z","end":"2026-04-02T08:47:07.724174Z","steps":["trace[716173734] 'process raft request' (duration: 115.032964ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:08.518763Z","caller":"traceutil/trace.go:171","msg":"trace[906472096] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:752; }","duration":"196.669665ms","start":"2026-04-02T08:47:08.322061Z","end":"2026-04-02T08:47:08.518731Z","steps":["trace[906472096] 'read index received' (duration: 195.816357ms)","trace[906472096] 'applied index is now lower than readState.Index' (duration: 851.978µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:47:08.518819Z","caller":"traceutil/trace.go:171","msg":"trace[1695674355] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"307.594397ms","start":"2026-04-02T08:47:08.211185Z","end":"2026-04-02T08:47:08.518779Z","steps":["trace[1695674355] 'process raft request' (duration: 306.904613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:08.518934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.846679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:47:08.51903Z","caller":"traceutil/trace.go:171","msg":"trace[1719598851] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:716; }","duration":"197.004862ms","start":"2026-04-02T08:47:08.322004Z","end":"2026-04-02T08:47:08.519009Z","steps":["trace[1719598851] 'agreement among raft nodes before linearized reading' (duration: 196.810378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:08.519178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:08.211154Z","time spent":"307.872984ms","remote":"127.0.0.1:59058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036233,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:47:08.521806Z","caller":"traceutil/trace.go:171","msg":"trace[252483826] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"138.746157ms","start":"2026-04-02T08:47:08.383029Z","end":"2026-04-02T08:47:08.521775Z","steps":["trace[252483826] 'process raft request' (duration: 138.633294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:08.521923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.626156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sd4nl\" ","response":"range_response_count:1 size:17243"} {"level":"info","ts":"2026-04-02T08:47:08.522054Z","caller":"traceutil/trace.go:171","msg":"trace[1876869675] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sd4nl; range_end:; response_count:1; response_revision:717; }","duration":"139.702558ms","start":"2026-04-02T08:47:08.382268Z","end":"2026-04-02T08:47:08.521971Z","steps":["trace[1876869675] 'agreement among raft nodes before linearized reading' (duration: 139.487043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:10.080132Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346108093638402442,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T08:47:10.228937Z","caller":"traceutil/trace.go:171","msg":"trace[607245081] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"703.802103ms","start":"2026-04-02T08:47:09.525052Z","end":"2026-04-02T08:47:10.228854Z","steps":["trace[607245081] 'process raft request' (duration: 703.361724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:10.229267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:09.525027Z","time spent":"704.068328ms","remote":"127.0.0.1:59058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:47:10.402803Z","caller":"traceutil/trace.go:171","msg":"trace[1519316050] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:771; }","duration":"823.433649ms","start":"2026-04-02T08:47:09.579351Z","end":"2026-04-02T08:47:10.402785Z","steps":["trace[1519316050] 'read index received' (duration: 648.89898ms)","trace[1519316050] 'applied index is now lower than readState.Index' (duration: 174.533449ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:47:10.402982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.618153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:47:10.402965Z","caller":"traceutil/trace.go:171","msg":"trace[1565592358] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"866.639752ms","start":"2026-04-02T08:47:09.536296Z","end":"2026-04-02T08:47:10.402936Z","steps":["trace[1565592358] 'process raft request' (duration: 866.375836ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:10.403014Z","caller":"traceutil/trace.go:171","msg":"trace[278684461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:735; }","duration":"823.703505ms","start":"2026-04-02T08:47:09.5793Z","end":"2026-04-02T08:47:10.403004Z","steps":["trace[278684461] 'agreement among raft nodes before linearized reading' (duration: 823.614283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:10.403153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:09.579278Z","time spent":"823.867408ms","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T08:47:10.403138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:09.536273Z","time spent":"866.787985ms","remote":"127.0.0.1:59034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:47:10.406081Z","caller":"traceutil/trace.go:171","msg":"trace[321961337] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"115.029671ms","start":"2026-04-02T08:47:10.29104Z","end":"2026-04-02T08:47:10.406069Z","steps":["trace[321961337] 'process raft request' (duration: 114.745425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:10.406501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.742956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-02T08:47:10.406553Z","caller":"traceutil/trace.go:171","msg":"trace[883504788] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:736; }","duration":"115.810748ms","start":"2026-04-02T08:47:10.290717Z","end":"2026-04-02T08:47:10.406528Z","steps":["trace[883504788] 'agreement among raft nodes before linearized reading' (duration: 115.713076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:10.406766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.091977ms","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-02T08:47:10.406888Z","caller":"traceutil/trace.go:171","msg":"trace[1665452085] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:736; }","duration":"380.251441ms","start":"2026-04-02T08:47:10.026626Z","end":"2026-04-02T08:47:10.406878Z","steps":["trace[1665452085] 'agreement among raft nodes before linearized reading' (duration: 380.115028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:10.407233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:10.02658Z","time spent":"380.640729ms","remote":"127.0.0.1:59464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":31,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-02T08:47:12.831948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.206016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-04-02T08:47:12.832026Z","caller":"traceutil/trace.go:171","msg":"trace[1978548585] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:794; }","duration":"181.329528ms","start":"2026-04-02T08:47:12.650682Z","end":"2026-04-02T08:47:12.832011Z","steps":["trace[1978548585] 'range keys from in-memory index tree' (duration: 180.95209ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:18.689414Z","caller":"traceutil/trace.go:171","msg":"trace[194812570] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"107.290085ms","start":"2026-04-02T08:47:18.582083Z","end":"2026-04-02T08:47:18.689374Z","steps":["trace[194812570] 'process raft request' (duration: 105.787273ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:19.58576Z","caller":"traceutil/trace.go:171","msg":"trace[1967288353] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"139.072198ms","start":"2026-04-02T08:47:19.446659Z","end":"2026-04-02T08:47:19.585732Z","steps":["trace[1967288353] 'process raft request' (duration: 138.448075ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:20.729008Z","caller":"traceutil/trace.go:171","msg":"trace[200134064] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"141.711529ms","start":"2026-04-02T08:47:20.58726Z","end":"2026-04-02T08:47:20.728972Z","steps":["trace[200134064] 'process raft request' (duration: 141.629488ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:20.729554Z","caller":"traceutil/trace.go:171","msg":"trace[458421238] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"142.539167ms","start":"2026-04-02T08:47:20.586421Z","end":"2026-04-02T08:47:20.72896Z","steps":["trace[458421238] 'process raft request' (duration: 141.25665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:25.079848Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346108093638402747,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:47:25.266077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073206891s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-04-02T08:47:25.511911Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.026018632s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T08:47:25.512372Z","caller":"traceutil/trace.go:171","msg":"trace[1823998966] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"1.026569283s","start":"2026-04-02T08:47:24.485773Z","end":"2026-04-02T08:47:25.512343Z","steps":["trace[1823998966] 'process raft request' (duration: 1.026281407s)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:25.512322Z","caller":"traceutil/trace.go:171","msg":"trace[329846257] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"933.117627ms","start":"2026-04-02T08:47:24.579119Z","end":"2026-04-02T08:47:25.512237Z","steps":["trace[329846257] 'read index received' (duration: 932.886782ms)","trace[329846257] 'applied index is now lower than readState.Index' (duration: 230.095µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:47:25.512501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:24.485754Z","time spent":"1.026683385s","remote":"127.0.0.1:59196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:47:25.512635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.478094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:47:25.513047Z","caller":"traceutil/trace.go:171","msg":"trace[1413799914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:884; }","duration":"933.945584ms","start":"2026-04-02T08:47:24.579071Z","end":"2026-04-02T08:47:25.513017Z","steps":["trace[1413799914] 'agreement among raft nodes before linearized reading' (duration: 933.396433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:25.5131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:24.579051Z","time spent":"934.038515ms","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T08:47:25.513192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.290375ms","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-02T08:47:25.51324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.321616ms","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-02T08:47:25.513346Z","caller":"traceutil/trace.go:171","msg":"trace[211005997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:884; }","duration":"113.49271ms","start":"2026-04-02T08:47:25.399829Z","end":"2026-04-02T08:47:25.513322Z","steps":["trace[211005997] 'agreement among raft nodes before linearized reading' (duration: 113.204134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:25.513373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.101102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T08:47:25.513401Z","caller":"traceutil/trace.go:171","msg":"trace[1427954554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:884; }","duration":"139.163784ms","start":"2026-04-02T08:47:25.37423Z","end":"2026-04-02T08:47:25.513394Z","steps":["trace[1427954554] 'agreement among raft nodes before linearized reading' (duration: 139.086282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:25.51355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.392533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T08:47:25.513584Z","caller":"traceutil/trace.go:171","msg":"trace[712377787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:884; }","duration":"145.459135ms","start":"2026-04-02T08:47:25.368116Z","end":"2026-04-02T08:47:25.513575Z","steps":["trace[712377787] 'agreement among raft nodes before linearized reading' (duration: 145.380773ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:25.513281Z","caller":"traceutil/trace.go:171","msg":"trace[1406254992] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:884; }","duration":"112.414238ms","start":"2026-04-02T08:47:25.400841Z","end":"2026-04-02T08:47:25.513255Z","steps":["trace[1406254992] 'agreement among raft nodes before linearized reading' (duration: 112.201344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:25.514477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.309231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-02T08:47:25.514525Z","caller":"traceutil/trace.go:171","msg":"trace[1609380358] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:884; }","duration":"922.385893ms","start":"2026-04-02T08:47:24.592127Z","end":"2026-04-02T08:47:25.514513Z","steps":["trace[1609380358] 'agreement among raft nodes before linearized reading' (duration: 920.883992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:25.514569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:47:24.59211Z","time spent":"922.451964ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-02T08:47:37.409536Z","caller":"traceutil/trace.go:171","msg":"trace[1349614486] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"145.390175ms","start":"2026-04-02T08:47:37.264118Z","end":"2026-04-02T08:47:37.409508Z","steps":["trace[1349614486] 'process raft request' (duration: 102.706861ms)","trace[1349614486] 'compare' (duration: 42.539861ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:47:37.75633Z","caller":"traceutil/trace.go:171","msg":"trace[1530951233] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"116.905783ms","start":"2026-04-02T08:47:37.6394Z","end":"2026-04-02T08:47:37.756305Z","steps":["trace[1530951233] 'process raft request' (duration: 116.821781ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:37.756434Z","caller":"traceutil/trace.go:171","msg":"trace[1773465989] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"117.029145ms","start":"2026-04-02T08:47:37.639302Z","end":"2026-04-02T08:47:37.756331Z","steps":["trace[1773465989] 'process raft request' (duration: 116.79036ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:37.757632Z","caller":"traceutil/trace.go:171","msg":"trace[932420539] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"116.045375ms","start":"2026-04-02T08:47:37.641448Z","end":"2026-04-02T08:47:37.757494Z","steps":["trace[932420539] 'process raft request' (duration: 114.81644ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:39.894099Z","caller":"traceutil/trace.go:171","msg":"trace[1376729661] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"109.917974ms","start":"2026-04-02T08:47:39.784164Z","end":"2026-04-02T08:47:39.894082Z","steps":["trace[1376729661] 'process raft request' (duration: 109.821642ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:48:10.825234Z","caller":"traceutil/trace.go:171","msg":"trace[532605365] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"119.5681ms","start":"2026-04-02T08:48:10.70564Z","end":"2026-04-02T08:48:10.825208Z","steps":["trace[532605365] 'process raft request' (duration: 119.434278ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:49:14.519435Z","caller":"traceutil/trace.go:171","msg":"trace[821854149] linearizableReadLoop","detail":"{readStateIndex:1470; appliedIndex:1469; }","duration":"341.902008ms","start":"2026-04-02T08:49:14.177499Z","end":"2026-04-02T08:49:14.519401Z","steps":["trace[821854149] 'read index received' (duration: 325.023827ms)","trace[821854149] 'applied index is now lower than readState.Index' (duration: 16.876341ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:49:14.519687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.719253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-02T08:49:14.519737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.927899ms","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-02T08:49:14.519804Z","caller":"traceutil/trace.go:171","msg":"trace[273354101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1403; }","duration":"342.064502ms","start":"2026-04-02T08:49:14.177717Z","end":"2026-04-02T08:49:14.519782Z","steps":["trace[273354101] 'agreement among raft nodes before linearized reading' (duration: 341.885038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:49:14.520199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:49:14.17766Z","time spent":"342.522804ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T08:49:14.519759Z","caller":"traceutil/trace.go:171","msg":"trace[946992287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1403; }","duration":"213.816146ms","start":"2026-04-02T08:49:14.305926Z","end":"2026-04-02T08:49:14.519742Z","steps":["trace[946992287] 'agreement among raft nodes before linearized reading' (duration: 213.677333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:49:14.51969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.169425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-02T08:49:14.520469Z","caller":"traceutil/trace.go:171","msg":"trace[1379622731] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1403; }","duration":"342.971264ms","start":"2026-04-02T08:49:14.177488Z","end":"2026-04-02T08:49:14.52046Z","steps":["trace[1379622731] 'agreement among raft nodes before linearized reading' (duration: 341.990951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:49:14.520498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:49:14.177451Z","time spent":"343.038626ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T08:49:14.519834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.102094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-02T08:49:14.520573Z","caller":"traceutil/trace.go:171","msg":"trace[1236501628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1403; }","duration":"219.861313ms","start":"2026-04-02T08:49:14.300683Z","end":"2026-04-02T08:49:14.520545Z","steps":["trace[1236501628] 'agreement among raft nodes before linearized reading' (duration: 219.058753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:49:14.520086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.88863ms","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-02T08:49:14.520628Z","caller":"traceutil/trace.go:171","msg":"trace[430413144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1403; }","duration":"168.478163ms","start":"2026-04-02T08:49:14.352143Z","end":"2026-04-02T08:49:14.520621Z","steps":["trace[430413144] 'agreement among raft nodes before linearized reading' (duration: 167.639913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:49:22.876143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.014823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:49:22.876393Z","caller":"traceutil/trace.go:171","msg":"trace[692691036] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"282.424163ms","start":"2026-04-02T08:49:22.593939Z","end":"2026-04-02T08:49:22.876363Z","steps":["trace[692691036] 'process raft request' (duration: 55.021471ms)","trace[692691036] 'compare' (duration: 226.541001ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:49:23.403786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.826075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:49:23.403865Z","caller":"traceutil/trace.go:171","msg":"trace[1113900217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1441; }","duration":"370.955538ms","start":"2026-04-02T08:49:23.03289Z","end":"2026-04-02T08:49:23.403845Z","steps":["trace[1113900217] 'range keys from in-memory index tree' (duration: 370.747003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:49:23.403905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:49:23.032871Z","time spent":"371.02502ms","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T08:49:23.930862Z","caller":"traceutil/trace.go:171","msg":"trace[206042455] linearizableReadLoop","detail":"{readStateIndex:1510; appliedIndex:1509; }","duration":"354.340098ms","start":"2026-04-02T08:49:23.576497Z","end":"2026-04-02T08:49:23.930837Z","steps":["trace[206042455] 'read index received' (duration: 354.219035ms)","trace[206042455] 'applied index is now lower than readState.Index' (duration: 119.483µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:49:23.930988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.474252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:49:23.931024Z","caller":"traceutil/trace.go:171","msg":"trace[699520472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1442; }","duration":"354.564693ms","start":"2026-04-02T08:49:23.576445Z","end":"2026-04-02T08:49:23.93101Z","steps":["trace[699520472] 'agreement among raft nodes before linearized reading' (duration: 354.489472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:49:23.931063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:49:23.576427Z","time spent":"354.627725ms","remote":"127.0.0.1:46302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T08:49:23.931187Z","caller":"traceutil/trace.go:171","msg":"trace[264604698] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"813.711557ms","start":"2026-04-02T08:49:23.117425Z","end":"2026-04-02T08:49:23.931136Z","steps":["trace[264604698] 'process raft request' (duration: 813.241906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:49:23.931504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:49:23.117404Z","time spent":"813.883321ms","remote":"127.0.0.1:59232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:49:24.065579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.4216ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-02T08:49:41.029632Z","caller":"traceutil/trace.go:171","msg":"trace[1337205947] transaction","detail":"{read_only:false; response_revision:1504; number_of_response:1; }","duration":"125.927782ms","start":"2026-04-02T08:49:40.903676Z","end":"2026-04-02T08:49:41.029604Z","steps":["trace[1337205947] 'process raft request' (duration: 123.323861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:49:42.532462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.419304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:1059"} {"level":"info","ts":"2026-04-02T08:49:42.532523Z","caller":"traceutil/trace.go:171","msg":"trace[1484232299] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1507; }","duration":"255.500656ms","start":"2026-04-02T08:49:42.277006Z","end":"2026-04-02T08:49:42.532507Z","steps":["trace[1484232299] 'range keys from in-memory index tree' (duration: 255.319792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:49:42.53258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.13039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/\" range_end:\"/registry/roles/envoy-gateway-system0\" limit:500 ","response":"range_response_count:3 size:3333"} {"level":"warn","ts":"2026-04-02T08:49:42.532596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.524664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/\" range_end:\"/registry/persistentvolumeclaims/default0\" limit:500 ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-04-02T08:49:42.532622Z","caller":"traceutil/trace.go:171","msg":"trace[252950641] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/; range_end:/registry/roles/envoy-gateway-system0; response_count:3; response_revision:1507; }","duration":"256.201832ms","start":"2026-04-02T08:49:42.276406Z","end":"2026-04-02T08:49:42.532608Z","steps":["trace[252950641] 'range keys from in-memory index tree' (duration: 256.028268ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:49:42.532637Z","caller":"traceutil/trace.go:171","msg":"trace[962652488] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/; range_end:/registry/persistentvolumeclaims/default0; response_count:1; response_revision:1507; }","duration":"207.598876ms","start":"2026-04-02T08:49:42.325027Z","end":"2026-04-02T08:49:42.532625Z","steps":["trace[962652488] 'range keys from in-memory index tree' (duration: 207.3637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:49:42.533024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.481582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager\" ","response":"range_response_count:1 size:1111"} {"level":"info","ts":"2026-04-02T08:49:42.53307Z","caller":"traceutil/trace.go:171","msg":"trace[569967867] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager; range_end:; response_count:1; response_revision:1507; }","duration":"248.560243ms","start":"2026-04-02T08:49:42.284497Z","end":"2026-04-02T08:49:42.533057Z","steps":["trace[569967867] 'range keys from in-memory index tree' (duration: 247.887128ms)"],"step_count":1}