{"level":"warn","ts":"2026-04-22T00:22:17.362699Z","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-22T00:22:17.362791Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-22T00:22:17.362874Z","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-22T00:22:17.362899Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-04-22T00:22:17.362922Z","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-22T00:22:17.363754Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-04-22T00:22:17.363917Z","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.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-22T00:22:17.370817Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.615209ms"} {"level":"info","ts":"2026-04-22T00:22:17.38081Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-04-22T00:22:17.380956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T00:22:17.380999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-04-22T00:22:17.381022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T00:22:17.381046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-04-22T00:22:17.381115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-04-22T00:22:17.393491Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T00:22:17.395781Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T00:22:17.397087Z","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-22T00:22:17.399424Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T00:22:17.400243Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T00:22:17.400362Z","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-22T00:22:17.400408Z","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-22T00:22:17.400423Z","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-22T00:22:17.402737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-04-22T00:22:17.402877Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-04-22T00:22:17.40613Z","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-22T00:22:17.406337Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-04-22T00:22:17.406404Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-04-22T00:22:17.406422Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T00:22:17.406451Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T00:22:17.981849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T00:22:17.98193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T00:22:17.98199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-04-22T00:22:17.98201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-04-22T00:22:17.982021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-04-22T00:22:17.982037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-04-22T00:22:17.98205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-04-22T00:22:18.022682Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T00:22:18.039373Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T00:22:18.039374Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T00:22:18.039608Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T00:22:18.043327Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-04-22T00:22:18.043678Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T00:22:18.050259Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T00:22:18.050294Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T00:22:18.053001Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T00:22:18.053393Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T00:22:18.05349Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-22T00:22:19.91832Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938290985237751159,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T00:22:20.028929Z","caller":"traceutil/trace.go:171","msg":"trace[233705938] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"619.12298ms","start":"2026-04-22T00:22:19.409776Z","end":"2026-04-22T00:22:20.028899Z","steps":["trace[233705938] 'process raft request' (duration: 618.884648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:20.029492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.409706Z","time spent":"619.315192ms","remote":"127.0.0.1:42726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:22:22.542929Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.514389998s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T00:22:22.543839Z","caller":"traceutil/trace.go:171","msg":"trace[394414179] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"3.051754695s","start":"2026-04-22T00:22:19.492046Z","end":"2026-04-22T00:22:22.543801Z","steps":["trace[394414179] 'process raft request' (duration: 3.051555084s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:22.544509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.49201Z","time spent":"3.05240936s","remote":"127.0.0.1:42966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:22:22.543863Z","caller":"traceutil/trace.go:171","msg":"trace[663209407] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"3.130805538s","start":"2026-04-22T00:22:19.412986Z","end":"2026-04-22T00:22:22.543791Z","steps":["trace[663209407] 'process raft request' (duration: 3.130249054s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:22.544886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.412969Z","time spent":"3.131696964s","remote":"127.0.0.1:42736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:22.544019Z","caller":"traceutil/trace.go:171","msg":"trace[1657530133] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"3.051569864s","start":"2026-04-22T00:22:19.492334Z","end":"2026-04-22T00:22:22.543904Z","steps":["trace[1657530133] 'process raft request' (duration: 3.051410583s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:22.544037Z","caller":"traceutil/trace.go:171","msg":"trace[663114976] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:4; }","duration":"3.125939232s","start":"2026-04-22T00:22:19.417997Z","end":"2026-04-22T00:22:22.543936Z","steps":["trace[663114976] 'read index received' (duration: 610.708418ms)","trace[663114976] 'applied index is now lower than readState.Index' (duration: 2.515229634s)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:22.545116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.492318Z","time spent":"3.052756012s","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:22:22.544129Z","caller":"traceutil/trace.go:171","msg":"trace[752443442] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"3.051949356s","start":"2026-04-22T00:22:19.49217Z","end":"2026-04-22T00:22:22.544119Z","steps":["trace[752443442] 'process raft request' (duration: 3.051533533s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:22.545298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.492144Z","time spent":"3.053086725s","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:22:22.544189Z","caller":"traceutil/trace.go:171","msg":"trace[1085993479] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"3.051776866s","start":"2026-04-22T00:22:19.492402Z","end":"2026-04-22T00:22:22.544178Z","steps":["trace[1085993479] 'process raft request' (duration: 3.051375623s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:22.545371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.07962626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T00:22:22.545465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.003701411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:22.545463Z","caller":"traceutil/trace.go:171","msg":"trace[1276566914] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"3.079748261s","start":"2026-04-22T00:22:19.465683Z","end":"2026-04-22T00:22:22.545431Z","steps":["trace[1276566914] 'agreement among raft nodes before linearized reading' (duration: 3.07959196s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:22.545493Z","caller":"traceutil/trace.go:171","msg":"trace[760313335] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"3.003731601s","start":"2026-04-22T00:22:19.541754Z","end":"2026-04-22T00:22:22.545485Z","steps":["trace[760313335] 'agreement among raft nodes before linearized reading' (duration: 3.003668801s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:22.545508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.465668Z","time spent":"3.079831581s","remote":"127.0.0.1:42814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-22T00:22:22.54554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.541712Z","time spent":"3.003814392s","remote":"127.0.0.1:42814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-04-22T00:22:22.545602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.159317993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T00:22:22.545676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.069403655s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:22.545681Z","caller":"traceutil/trace.go:171","msg":"trace[1429736593] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"2.159418724s","start":"2026-04-22T00:22:20.386246Z","end":"2026-04-22T00:22:22.545664Z","steps":["trace[1429736593] 'agreement among raft nodes before linearized reading' (duration: 2.159279373s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:22.545709Z","caller":"traceutil/trace.go:171","msg":"trace[7462675] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:0; response_revision:11; }","duration":"3.069455606s","start":"2026-04-22T00:22:19.476242Z","end":"2026-04-22T00:22:22.545698Z","steps":["trace[7462675] 'agreement among raft nodes before linearized reading' (duration: 3.069415275s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:22.545715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.068059165s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T00:22:22.545746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.476231Z","time spent":"3.069509126s","remote":"127.0.0.1:42710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.204.45.157\" "} {"level":"info","ts":"2026-04-22T00:22:22.545763Z","caller":"traceutil/trace.go:171","msg":"trace[54987922] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"3.068131535s","start":"2026-04-22T00:22:19.47762Z","end":"2026-04-22T00:22:22.545752Z","steps":["trace[54987922] 'agreement among raft nodes before linearized reading' (duration: 3.068005894s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:22.54549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.492359Z","time spent":"3.053064615s","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:22:22.545807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.47761Z","time spent":"3.068190467s","remote":"127.0.0.1:42814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-22T00:22:22.545757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:20.386231Z","time spent":"2.159512664s","remote":"127.0.0.1:43046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-04-22T00:22:22.54564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.006442571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T00:22:22.546032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.044513251s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:22.546058Z","caller":"traceutil/trace.go:171","msg":"trace[1377742631] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"3.044564202s","start":"2026-04-22T00:22:19.501483Z","end":"2026-04-22T00:22:22.546047Z","steps":["trace[1377742631] 'agreement among raft nodes before linearized reading' (duration: 3.044525892s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:22.544317Z","caller":"traceutil/trace.go:171","msg":"trace[202051279] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"3.065609488s","start":"2026-04-22T00:22:19.478138Z","end":"2026-04-22T00:22:22.543747Z","steps":["trace[202051279] 'process raft request' (duration: 3.065416556s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:22.546057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.972629234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:22.546799Z","caller":"traceutil/trace.go:171","msg":"trace[1557720411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"1.9734189s","start":"2026-04-22T00:22:20.573367Z","end":"2026-04-22T00:22:22.546785Z","steps":["trace[1557720411] 'agreement among raft nodes before linearized reading' (duration: 1.972414162s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:22.546877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:20.573338Z","time spent":"1.97352283s","remote":"127.0.0.1:42654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T00:22:22.54605Z","caller":"traceutil/trace.go:171","msg":"trace[409375565] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"3.006849844s","start":"2026-04-22T00:22:19.539189Z","end":"2026-04-22T00:22:22.546039Z","steps":["trace[409375565] 'agreement among raft nodes before linearized reading' (duration: 3.006428081s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:22.547395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.539163Z","time spent":"3.008217334s","remote":"127.0.0.1:42814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-22T00:22:22.544325Z","caller":"traceutil/trace.go:171","msg":"trace[1952691547] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"3.04843422s","start":"2026-04-22T00:22:19.495813Z","end":"2026-04-22T00:22:22.544247Z","steps":["trace[1952691547] 'process raft request' (duration: 3.048075808s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:22.548296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.495796Z","time spent":"3.05242973s","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:22:22.54434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.126256144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:22.548657Z","caller":"traceutil/trace.go:171","msg":"trace[1480066097] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"3.130662016s","start":"2026-04-22T00:22:19.417969Z","end":"2026-04-22T00:22:22.548631Z","steps":["trace[1480066097] 'agreement among raft nodes before linearized reading' (duration: 3.126184164s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:22.548722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.417962Z","time spent":"3.130743317s","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-22T00:22:22.544198Z","caller":"traceutil/trace.go:171","msg":"trace[482621808] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"3.051456243s","start":"2026-04-22T00:22:19.492722Z","end":"2026-04-22T00:22:22.544178Z","steps":["trace[482621808] 'process raft request' (duration: 3.05112446s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:22.54891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.492708Z","time spent":"3.056150897s","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:22:22.545773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.168974783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:22.549021Z","caller":"traceutil/trace.go:171","msg":"trace[173722624] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"2.172286088s","start":"2026-04-22T00:22:20.376719Z","end":"2026-04-22T00:22:22.549005Z","steps":["trace[173722624] 'agreement among raft nodes before linearized reading' (duration: 2.169020394s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:22.54423Z","caller":"traceutil/trace.go:171","msg":"trace[185508351] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"3.051764155s","start":"2026-04-22T00:22:19.492458Z","end":"2026-04-22T00:22:22.544223Z","steps":["trace[185508351] 'process raft request' (duration: 3.051355892s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:22.549459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.492443Z","time spent":"3.056926622s","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:22:22.546079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.501469Z","time spent":"3.044605993s","remote":"127.0.0.1:42768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-22T00:22:22.54613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.4781Z","time spent":"3.067987275s","remote":"127.0.0.1:43152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:22:22.549294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:20.376685Z","time spent":"2.17259278s","remote":"127.0.0.1:43064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-04-22T00:22:22.820505Z","caller":"traceutil/trace.go:171","msg":"trace[1135277012] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:14; }","duration":"269.161786ms","start":"2026-04-22T00:22:22.551319Z","end":"2026-04-22T00:22:22.820481Z","steps":["trace[1135277012] 'read index received' (duration: 199.490501ms)","trace[1135277012] 'applied index is now lower than readState.Index' (duration: 69.669885ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:22.820602Z","caller":"traceutil/trace.go:171","msg":"trace[1245861090] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"269.339078ms","start":"2026-04-22T00:22:22.551253Z","end":"2026-04-22T00:22:22.820592Z","steps":["trace[1245861090] 'process raft request' (duration: 199.533122ms)","trace[1245861090] 'compare' (duration: 69.499224ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:22.820871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.534849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-sh54c\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-04-22T00:22:22.82091Z","caller":"traceutil/trace.go:171","msg":"trace[1658727915] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-sh54c; range_end:; response_count:1; response_revision:12; }","duration":"269.62407ms","start":"2026-04-22T00:22:22.551277Z","end":"2026-04-22T00:22:22.820901Z","steps":["trace[1658727915] 'agreement among raft nodes before linearized reading' (duration: 269.430629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.200265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.697941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-22T00:22:23.200607Z","caller":"traceutil/trace.go:171","msg":"trace[762951054] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"647.593723ms","start":"2026-04-22T00:22:22.552962Z","end":"2026-04-22T00:22:23.200556Z","steps":["trace[762951054] 'process raft request' (duration: 647.409662ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:23.200689Z","caller":"traceutil/trace.go:171","msg":"trace[1939045386] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"647.08222ms","start":"2026-04-22T00:22:22.553579Z","end":"2026-04-22T00:22:23.200661Z","steps":["trace[1939045386] 'process raft request' (duration: 646.950419ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:23.200765Z","caller":"traceutil/trace.go:171","msg":"trace[1082618232] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"647.711934ms","start":"2026-04-22T00:22:22.553043Z","end":"2026-04-22T00:22:23.200755Z","steps":["trace[1082618232] 'process raft request' (duration: 647.401532ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:23.200803Z","caller":"traceutil/trace.go:171","msg":"trace[1799971531] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"647.485283ms","start":"2026-04-22T00:22:22.553309Z","end":"2026-04-22T00:22:23.200795Z","steps":["trace[1799971531] 'process raft request' (duration: 647.18902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.200811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:22.552943Z","time spent":"647.770345ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:22:23.200829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:22.55357Z","time spent":"647.1934ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:22:23.200856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:22.553303Z","time spent":"647.526443ms","remote":"127.0.0.1:43064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:22:23.200668Z","caller":"traceutil/trace.go:171","msg":"trace[673529056] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"647.701904ms","start":"2026-04-22T00:22:22.552908Z","end":"2026-04-22T00:22:23.2006Z","steps":["trace[673529056] 'process raft request' (duration: 412.413759ms)","trace[673529056] 'compare' (duration: 234.5624ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:23.2009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:22.553035Z","time spent":"647.787075ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:22:23.200758Z","caller":"traceutil/trace.go:171","msg":"trace[465362277] transaction","detail":"{read_only:false; number_of_response:0; response_revision:14; }","duration":"647.767604ms","start":"2026-04-22T00:22:22.552973Z","end":"2026-04-22T00:22:23.20074Z","steps":["trace[465362277] 'process raft request' (duration: 647.450592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.201074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:22.552963Z","time spent":"648.087956ms","remote":"127.0.0.1:42814","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":26,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:22:23.201093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:22.552893Z","time spent":"648.095547ms","remote":"127.0.0.1:42814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:22:23.201394Z","caller":"traceutil/trace.go:171","msg":"trace[408370445] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"647.796755ms","start":"2026-04-22T00:22:22.553576Z","end":"2026-04-22T00:22:23.201373Z","steps":["trace[408370445] 'process raft request' (duration: 646.975939ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:23.201493Z","caller":"traceutil/trace.go:171","msg":"trace[2076461661] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"645.546738ms","start":"2026-04-22T00:22:22.555931Z","end":"2026-04-22T00:22:23.201478Z","steps":["trace[2076461661] 'process raft request' (duration: 645.418748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.201639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:22.553565Z","time spent":"648.027776ms","remote":"127.0.0.1:43152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:22:23.201821Z","caller":"traceutil/trace.go:171","msg":"trace[1424559223] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:15; }","duration":"381.243177ms","start":"2026-04-22T00:22:22.820563Z","end":"2026-04-22T00:22:23.201806Z","steps":["trace[1424559223] 'read index received' (duration: 144.737663ms)","trace[1424559223] 'applied index is now lower than readState.Index' (duration: 236.503324ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:23.201921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.573465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:23.201985Z","caller":"traceutil/trace.go:171","msg":"trace[1912413359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"650.652055ms","start":"2026-04-22T00:22:22.55132Z","end":"2026-04-22T00:22:23.201973Z","steps":["trace[1912413359] 'agreement among raft nodes before linearized reading' (duration: 650.536634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.20203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:22.551311Z","time spent":"650.703465ms","remote":"127.0.0.1:42654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T00:22:23.201641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:22.555919Z","time spent":"645.633819ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:22:23.202285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.395064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:23.202437Z","caller":"traceutil/trace.go:171","msg":"trace[1745763717] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:20; }","duration":"448.584805ms","start":"2026-04-22T00:22:22.753835Z","end":"2026-04-22T00:22:23.20242Z","steps":["trace[1745763717] 'agreement among raft nodes before linearized reading' (duration: 448.411164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.202498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:22.753805Z","time spent":"448.682246ms","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-04-22T00:22:23.202723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.563655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-22T00:22:23.201414Z","caller":"traceutil/trace.go:171","msg":"trace[476399196] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"647.709364ms","start":"2026-04-22T00:22:22.553619Z","end":"2026-04-22T00:22:23.201328Z","steps":["trace[476399196] 'process raft request' (duration: 647.628234ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:23.202768Z","caller":"traceutil/trace.go:171","msg":"trace[1131031605] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"591.667917ms","start":"2026-04-22T00:22:22.611091Z","end":"2026-04-22T00:22:23.202759Z","steps":["trace[1131031605] 'agreement among raft nodes before linearized reading' (duration: 591.560436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.202787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.704102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T00:22:23.202796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:22.611053Z","time spent":"591.735687ms","remote":"127.0.0.1:42814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-22T00:22:23.202822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:22.553488Z","time spent":"649.290486ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:22:23.202856Z","caller":"traceutil/trace.go:171","msg":"trace[474181275] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:20; }","duration":"600.814753ms","start":"2026-04-22T00:22:22.602025Z","end":"2026-04-22T00:22:23.20284Z","steps":["trace[474181275] 'agreement among raft nodes before linearized reading' (duration: 600.701362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.202904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:22.602011Z","time spent":"600.878864ms","remote":"127.0.0.1:42814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-04-22T00:22:23.202897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.31238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-04-22T00:22:23.202793Z","caller":"traceutil/trace.go:171","msg":"trace[1784559664] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"377.086697ms","start":"2026-04-22T00:22:22.825692Z","end":"2026-04-22T00:22:23.202779Z","steps":["trace[1784559664] 'process raft request' (duration: 377.003786ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:23.203033Z","caller":"traceutil/trace.go:171","msg":"trace[102942185] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20; }","duration":"651.481521ms","start":"2026-04-22T00:22:22.551518Z","end":"2026-04-22T00:22:23.202999Z","steps":["trace[102942185] 'agreement among raft nodes before linearized reading' (duration: 651.192329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.203122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:22.825671Z","time spent":"377.413149ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:22:23.203123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:22.551511Z","time spent":"651.594402ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3513,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-22T00:22:23.203339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.748974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:23.203556Z","caller":"traceutil/trace.go:171","msg":"trace[1869555837] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:21; }","duration":"651.823644ms","start":"2026-04-22T00:22:22.551569Z","end":"2026-04-22T00:22:23.203393Z","steps":["trace[1869555837] 'agreement among raft nodes before linearized reading' (duration: 651.587172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.203613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:22.551529Z","time spent":"652.069556ms","remote":"127.0.0.1:43050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"info","ts":"2026-04-22T00:22:23.573093Z","caller":"traceutil/trace.go:171","msg":"trace[866257142] linearizableReadLoop","detail":"{readStateIndex:47; appliedIndex:46; }","duration":"153.29545ms","start":"2026-04-22T00:22:23.419757Z","end":"2026-04-22T00:22:23.573053Z","steps":["trace[866257142] 'read index received' (duration: 82.045554ms)","trace[866257142] 'applied index is now lower than readState.Index' (duration: 71.248466ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:23.573134Z","caller":"traceutil/trace.go:171","msg":"trace[286217297] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"160.932255ms","start":"2026-04-22T00:22:23.412183Z","end":"2026-04-22T00:22:23.573115Z","steps":["trace[286217297] 'process raft request' (duration: 89.684009ms)","trace[286217297] 'compare' (duration: 70.944234ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:23.573357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.944435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:23.573488Z","caller":"traceutil/trace.go:171","msg":"trace[592159606] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:43; }","duration":"161.099037ms","start":"2026-04-22T00:22:23.412378Z","end":"2026-04-22T00:22:23.573477Z","steps":["trace[592159606] 'agreement among raft nodes before linearized reading' (duration: 160.806875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.573412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.479458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:23.573569Z","caller":"traceutil/trace.go:171","msg":"trace[1777860901] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:43; }","duration":"151.668769ms","start":"2026-04-22T00:22:23.421891Z","end":"2026-04-22T00:22:23.57356Z","steps":["trace[1777860901] 'agreement among raft nodes before linearized reading' (duration: 151.358856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.573598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.333849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:23.573629Z","caller":"traceutil/trace.go:171","msg":"trace[387429088] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:43; }","duration":"150.388619ms","start":"2026-04-22T00:22:23.423231Z","end":"2026-04-22T00:22:23.573619Z","steps":["trace[387429088] 'agreement among raft nodes before linearized reading' (duration: 150.332909ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:23.779841Z","caller":"traceutil/trace.go:171","msg":"trace[42809182] linearizableReadLoop","detail":"{readStateIndex:50; appliedIndex:48; }","duration":"186.902302ms","start":"2026-04-22T00:22:23.592913Z","end":"2026-04-22T00:22:23.779815Z","steps":["trace[42809182] 'read index received' (duration: 110.749393ms)","trace[42809182] 'applied index is now lower than readState.Index' (duration: 76.151649ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:23.779998Z","caller":"traceutil/trace.go:171","msg":"trace[334891842] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"202.238514ms","start":"2026-04-22T00:22:23.577743Z","end":"2026-04-22T00:22:23.779981Z","steps":["trace[334891842] 'process raft request' (duration: 125.902572ms)","trace[334891842] 'compare' (duration: 75.689134ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:23.780219Z","caller":"traceutil/trace.go:171","msg":"trace[518323900] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"201.074906ms","start":"2026-04-22T00:22:23.579116Z","end":"2026-04-22T00:22:23.780191Z","steps":["trace[518323900] 'process raft request' (duration: 200.604901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.780307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.777878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:23.780359Z","caller":"traceutil/trace.go:171","msg":"trace[578764333] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:0; response_revision:46; }","duration":"184.874999ms","start":"2026-04-22T00:22:23.595473Z","end":"2026-04-22T00:22:23.780348Z","steps":["trace[578764333] 'agreement among raft nodes before linearized reading' (duration: 184.708437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.780436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.067187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a885f400958c4d\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:23.780484Z","caller":"traceutil/trace.go:171","msg":"trace[900587036] range","detail":"{range_begin:/registry/events/default/instance.18a885f400958c4d; range_end:; response_count:0; response_revision:46; }","duration":"201.209039ms","start":"2026-04-22T00:22:23.579264Z","end":"2026-04-22T00:22:23.780473Z","steps":["trace[900587036] 'agreement among raft nodes before linearized reading' (duration: 201.075677ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:23.942427Z","caller":"traceutil/trace.go:171","msg":"trace[524374359] linearizableReadLoop","detail":"{readStateIndex:58; appliedIndex:55; }","duration":"117.660934ms","start":"2026-04-22T00:22:23.824712Z","end":"2026-04-22T00:22:23.942373Z","steps":["trace[524374359] 'read index received' (duration: 46.554924ms)","trace[524374359] 'applied index is now lower than readState.Index' (duration: 71.103ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:23.94248Z","caller":"traceutil/trace.go:171","msg":"trace[558356268] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"118.897629ms","start":"2026-04-22T00:22:23.823548Z","end":"2026-04-22T00:22:23.942445Z","steps":["trace[558356268] 'process raft request' (duration: 74.121267ms)","trace[558356268] 'compare' (duration: 44.443098ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:23.943053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.319712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a885f40095df13\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:23.943863Z","caller":"traceutil/trace.go:171","msg":"trace[491185719] range","detail":"{range_begin:/registry/events/default/instance.18a885f40095df13; range_end:; response_count:0; response_revision:55; }","duration":"118.420903ms","start":"2026-04-22T00:22:23.824683Z","end":"2026-04-22T00:22:23.943104Z","steps":["trace[491185719] 'agreement among raft nodes before linearized reading' (duration: 117.821626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:24.337129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.373822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-22T00:22:24.337343Z","caller":"traceutil/trace.go:171","msg":"trace[1822610978] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"206.339053ms","start":"2026-04-22T00:22:24.130987Z","end":"2026-04-22T00:22:24.337327Z","steps":["trace[1822610978] 'process raft request' (duration: 206.288641ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:24.337389Z","caller":"traceutil/trace.go:171","msg":"trace[746588095] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"227.168457ms","start":"2026-04-22T00:22:24.110173Z","end":"2026-04-22T00:22:24.337341Z","steps":["trace[746588095] 'process raft request' (duration: 227.043371ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:24.337535Z","caller":"traceutil/trace.go:171","msg":"trace[1927678679] linearizableReadLoop","detail":"{readStateIndex:83; appliedIndex:80; }","duration":"227.078252ms","start":"2026-04-22T00:22:24.110446Z","end":"2026-04-22T00:22:24.337524Z","steps":["trace[1927678679] 'read index received' (duration: 18.336838ms)","trace[1927678679] 'applied index is now lower than readState.Index' (duration: 208.740284ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:24.337664Z","caller":"traceutil/trace.go:171","msg":"trace[1220174661] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"229.009019ms","start":"2026-04-22T00:22:24.108639Z","end":"2026-04-22T00:22:24.337648Z","steps":["trace[1220174661] 'process raft request' (duration: 62.042788ms)","trace[1220174661] 'compare' (duration: 166.273907ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:24.337865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.424912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T00:22:24.337851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.382187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:24.337924Z","caller":"traceutil/trace.go:171","msg":"trace[515769617] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:79; }","duration":"227.502493ms","start":"2026-04-22T00:22:24.110408Z","end":"2026-04-22T00:22:24.33791Z","steps":["trace[515769617] 'agreement among raft nodes before linearized reading' (duration: 227.374647ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:24.337924Z","caller":"traceutil/trace.go:171","msg":"trace[1465753449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:79; }","duration":"124.503725ms","start":"2026-04-22T00:22:24.21339Z","end":"2026-04-22T00:22:24.337894Z","steps":["trace[1465753449] 'agreement among raft nodes before linearized reading' (duration: 124.450623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:24.586006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.235163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-22T00:22:24.586124Z","caller":"traceutil/trace.go:171","msg":"trace[1253470234] linearizableReadLoop","detail":"{readStateIndex:103; appliedIndex:102; }","duration":"155.581821ms","start":"2026-04-22T00:22:24.43052Z","end":"2026-04-22T00:22:24.586102Z","steps":["trace[1253470234] 'read index received' (duration: 8.115976ms)","trace[1253470234] 'applied index is now lower than readState.Index' (duration: 147.464655ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:24.586167Z","caller":"traceutil/trace.go:171","msg":"trace[1416143427] transaction","detail":"{read_only:false; response_revision:98; number_of_response:1; }","duration":"158.564671ms","start":"2026-04-22T00:22:24.42757Z","end":"2026-04-22T00:22:24.586134Z","steps":["trace[1416143427] 'process raft request' (duration: 11.109827ms)","trace[1416143427] 'compare' (duration: 147.112707ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:24.586282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.748209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-problem-detector\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:24.586342Z","caller":"traceutil/trace.go:171","msg":"trace[145164864] range","detail":"{range_begin:/registry/clusterroles/system:node-problem-detector; range_end:; response_count:0; response_revision:98; }","duration":"155.821193ms","start":"2026-04-22T00:22:24.430505Z","end":"2026-04-22T00:22:24.586326Z","steps":["trace[145164864] 'agreement among raft nodes before linearized reading' (duration: 155.679246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:25.372477Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938290985237751317,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T00:22:25.657261Z","caller":"traceutil/trace.go:171","msg":"trace[872227725] linearizableReadLoop","detail":"{readStateIndex:109; appliedIndex:108; }","duration":"785.083659ms","start":"2026-04-22T00:22:24.872148Z","end":"2026-04-22T00:22:25.657232Z","steps":["trace[872227725] 'read index received' (duration: 743.810657ms)","trace[872227725] 'applied index is now lower than readState.Index' (duration: 41.271382ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:25.657286Z","caller":"traceutil/trace.go:171","msg":"trace[648553663] transaction","detail":"{read_only:false; response_revision:104; number_of_response:1; }","duration":"785.89365ms","start":"2026-04-22T00:22:24.871369Z","end":"2026-04-22T00:22:25.657263Z","steps":["trace[648553663] 'process raft request' (duration: 744.45651ms)","trace[648553663] 'compare' (duration: 41.284242ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:25.657465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.927674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T00:22:25.657487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.289649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:auth-delegator\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:25.657524Z","caller":"traceutil/trace.go:171","msg":"trace[96568601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:104; }","duration":"345.038759ms","start":"2026-04-22T00:22:25.312471Z","end":"2026-04-22T00:22:25.65751Z","steps":["trace[96568601] 'agreement among raft nodes before linearized reading' (duration: 344.940604ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:25.657559Z","caller":"traceutil/trace.go:171","msg":"trace[1637212393] range","detail":"{range_begin:/registry/clusterroles/system:auth-delegator; range_end:; response_count:0; response_revision:104; }","duration":"785.399235ms","start":"2026-04-22T00:22:24.872145Z","end":"2026-04-22T00:22:25.657544Z","steps":["trace[1637212393] 'agreement among raft nodes before linearized reading' (duration: 785.190914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:25.657541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:24.87135Z","time spent":"786.085889ms","remote":"127.0.0.1:42752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:22:25.657584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:25.312453Z","time spent":"345.116643ms","remote":"127.0.0.1:42664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T00:22:25.657602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:24.872087Z","time spent":"785.50209ms","remote":"127.0.0.1:43046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:auth-delegator\" "} {"level":"warn","ts":"2026-04-22T00:22:26.17965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.636894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-22T00:22:26.179853Z","caller":"traceutil/trace.go:171","msg":"trace[125128036] transaction","detail":"{read_only:false; response_revision:107; number_of_response:1; }","duration":"449.850699ms","start":"2026-04-22T00:22:25.729982Z","end":"2026-04-22T00:22:26.179833Z","steps":["trace[125128036] 'process raft request' (duration: 449.783376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:26.180003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:25.729962Z","time spent":"450.010367ms","remote":"127.0.0.1:43046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":582,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:22:26.180022Z","caller":"traceutil/trace.go:171","msg":"trace[570321562] transaction","detail":"{read_only:false; response_revision:106; number_of_response:1; }","duration":"452.617695ms","start":"2026-04-22T00:22:25.727377Z","end":"2026-04-22T00:22:26.179995Z","steps":["trace[570321562] 'process raft request' (duration: 51.813973ms)","trace[570321562] 'compare' (duration: 400.269836ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:26.180163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:25.727347Z","time spent":"452.726011ms","remote":"127.0.0.1:42752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:26.593231Z","caller":"traceutil/trace.go:171","msg":"trace[945792753] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"407.823751ms","start":"2026-04-22T00:22:26.18534Z","end":"2026-04-22T00:22:26.593164Z","steps":["trace[945792753] 'process raft request' (duration: 342.079228ms)","trace[945792753] 'compare' (duration: 65.541153ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:26.593387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:26.185321Z","time spent":"407.992819ms","remote":"127.0.0.1:42752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:26.601904Z","caller":"traceutil/trace.go:171","msg":"trace[1726315094] linearizableReadLoop","detail":"{readStateIndex:114; appliedIndex:112; }","duration":"288.674797ms","start":"2026-04-22T00:22:26.313215Z","end":"2026-04-22T00:22:26.60189Z","steps":["trace[1726315094] 'read index received' (duration: 214.216708ms)","trace[1726315094] 'applied index is now lower than readState.Index' (duration: 74.457299ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:26.601969Z","caller":"traceutil/trace.go:171","msg":"trace[832973327] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"415.78605ms","start":"2026-04-22T00:22:26.186177Z","end":"2026-04-22T00:22:26.601963Z","steps":["trace[832973327] 'process raft request' (duration: 415.654654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:26.602019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:26.186169Z","time spent":"415.815681ms","remote":"127.0.0.1:43046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1026,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:22:26.602071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.858297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:26.602112Z","caller":"traceutil/trace.go:171","msg":"trace[1644920185] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:109; }","duration":"288.937801ms","start":"2026-04-22T00:22:26.313165Z","end":"2026-04-22T00:22:26.602103Z","steps":["trace[1644920185] 'agreement among raft nodes before linearized reading' (duration: 288.845066ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:26.770731Z","caller":"traceutil/trace.go:171","msg":"trace[300836603] transaction","detail":"{read_only:false; response_revision:111; number_of_response:1; }","duration":"163.812526ms","start":"2026-04-22T00:22:26.606874Z","end":"2026-04-22T00:22:26.770687Z","steps":["trace[300836603] 'process raft request' (duration: 136.767364ms)","trace[300836603] 'compare' (duration: 26.755088ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:27.047294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.704728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-22T00:22:27.047388Z","caller":"traceutil/trace.go:171","msg":"trace[56348044] linearizableReadLoop","detail":"{readStateIndex:119; appliedIndex:118; }","duration":"218.467732ms","start":"2026-04-22T00:22:26.828907Z","end":"2026-04-22T00:22:27.047375Z","steps":["trace[56348044] 'read index received' (duration: 83.541253ms)","trace[56348044] 'applied index is now lower than readState.Index' (duration: 134.925208ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:27.047413Z","caller":"traceutil/trace.go:171","msg":"trace[714546031] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"249.72311ms","start":"2026-04-22T00:22:26.797658Z","end":"2026-04-22T00:22:27.047381Z","steps":["trace[714546031] 'process raft request' (duration: 114.849783ms)","trace[714546031] 'compare' (duration: 134.544121ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:27.047501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.588238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:27.047528Z","caller":"traceutil/trace.go:171","msg":"trace[552472790] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient; range_end:; response_count:0; response_revision:114; }","duration":"218.664272ms","start":"2026-04-22T00:22:26.828855Z","end":"2026-04-22T00:22:27.04752Z","steps":["trace[552472790] 'agreement among raft nodes before linearized reading' (duration: 218.554546ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:27.201857Z","caller":"traceutil/trace.go:171","msg":"trace[186953187] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"151.134448ms","start":"2026-04-22T00:22:27.050696Z","end":"2026-04-22T00:22:27.20183Z","steps":["trace[186953187] 'process raft request' (duration: 151.074195ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:27.201956Z","caller":"traceutil/trace.go:171","msg":"trace[1434850600] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"151.636232ms","start":"2026-04-22T00:22:27.050274Z","end":"2026-04-22T00:22:27.20191Z","steps":["trace[1434850600] 'process raft request' (duration: 99.338596ms)","trace[1434850600] 'compare' (duration: 51.97834ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:27.750581Z","caller":"traceutil/trace.go:171","msg":"trace[1413201082] linearizableReadLoop","detail":"{readStateIndex:123; appliedIndex:121; }","duration":"497.911372ms","start":"2026-04-22T00:22:27.252647Z","end":"2026-04-22T00:22:27.750559Z","steps":["trace[1413201082] 'read index received' (duration: 438.922953ms)","trace[1413201082] 'applied index is now lower than readState.Index' (duration: 58.987169ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:27.750849Z","caller":"traceutil/trace.go:171","msg":"trace[1605745592] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"545.301779ms","start":"2026-04-22T00:22:27.205372Z","end":"2026-04-22T00:22:27.750674Z","steps":["trace[1605745592] 'process raft request' (duration: 486.183765ms)","trace[1605745592] 'compare' (duration: 58.748296ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:27.750952Z","caller":"traceutil/trace.go:171","msg":"trace[2117720429] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"544.139443ms","start":"2026-04-22T00:22:27.206596Z","end":"2026-04-22T00:22:27.750736Z","steps":["trace[2117720429] 'process raft request' (duration: 543.914632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:27.750926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.907216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T00:22:27.751037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:27.206584Z","time spent":"544.412097ms","remote":"127.0.0.1:43046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:22:27.751052Z","caller":"traceutil/trace.go:171","msg":"trace[1332650610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:118; }","duration":"338.030442ms","start":"2026-04-22T00:22:27.412982Z","end":"2026-04-22T00:22:27.751012Z","steps":["trace[1332650610] 'agreement among raft nodes before linearized reading' (duration: 337.853613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:27.751108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:27.412923Z","time spent":"338.172179ms","remote":"127.0.0.1:42664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T00:22:27.750889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.094831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:27.751309Z","caller":"traceutil/trace.go:171","msg":"trace[1108833894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:118; }","duration":"498.557293ms","start":"2026-04-22T00:22:27.25273Z","end":"2026-04-22T00:22:27.751288Z","steps":["trace[1108833894] 'agreement among raft nodes before linearized reading' (duration: 498.039098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:27.751368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:27.20535Z","time spent":"545.691028ms","remote":"127.0.0.1:42752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":799,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:22:27.751398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:27.252695Z","time spent":"498.651758ms","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T00:22:27.750889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.222166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T00:22:27.751657Z","caller":"traceutil/trace.go:171","msg":"trace[1736127263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:118; }","duration":"499.031106ms","start":"2026-04-22T00:22:27.252604Z","end":"2026-04-22T00:22:27.751635Z","steps":["trace[1736127263] 'agreement among raft nodes before linearized reading' (duration: 498.135822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:27.75171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:27.252584Z","time spent":"499.109869ms","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T00:22:28.010573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.491415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-22T00:22:28.010843Z","caller":"traceutil/trace.go:171","msg":"trace[1020662627] transaction","detail":"{read_only:false; response_revision:123; number_of_response:1; }","duration":"162.893322ms","start":"2026-04-22T00:22:27.847912Z","end":"2026-04-22T00:22:28.010805Z","steps":["trace[1020662627] 'compare' (duration: 154.325178ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:28.010964Z","caller":"traceutil/trace.go:171","msg":"trace[497423045] linearizableReadLoop","detail":"{readStateIndex:128; appliedIndex:127; }","duration":"162.674621ms","start":"2026-04-22T00:22:27.848263Z","end":"2026-04-22T00:22:28.010937Z","steps":["trace[497423045] 'read index received' (duration: 7.742634ms)","trace[497423045] 'applied index is now lower than readState.Index' (duration: 154.927377ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:28.011019Z","caller":"traceutil/trace.go:171","msg":"trace[1896761418] transaction","detail":"{read_only:false; response_revision:124; number_of_response:1; }","duration":"162.521014ms","start":"2026-04-22T00:22:27.848471Z","end":"2026-04-22T00:22:28.010992Z","steps":["trace[1896761418] 'process raft request' (duration: 162.22606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:28.011176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.903532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T00:22:28.010993Z","caller":"traceutil/trace.go:171","msg":"trace[2029970347] transaction","detail":"{read_only:false; response_revision:125; number_of_response:1; }","duration":"160.898186ms","start":"2026-04-22T00:22:27.850073Z","end":"2026-04-22T00:22:28.010971Z","steps":["trace[2029970347] 'process raft request' (duration: 160.78861ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:28.01125Z","caller":"traceutil/trace.go:171","msg":"trace[1188796287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:125; }","duration":"163.030088ms","start":"2026-04-22T00:22:27.848202Z","end":"2026-04-22T00:22:28.011232Z","steps":["trace[1188796287] 'agreement among raft nodes before linearized reading' (duration: 162.817908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:28.228526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.406668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:22:28.228862Z","caller":"traceutil/trace.go:171","msg":"trace[1942161743] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"159.275249ms","start":"2026-04-22T00:22:28.069469Z","end":"2026-04-22T00:22:28.228745Z","steps":["trace[1942161743] 'process raft request' (duration: 44.530915ms)","trace[1942161743] 'compare' (duration: 114.211998ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:28.229321Z","caller":"traceutil/trace.go:171","msg":"trace[809935578] transaction","detail":"{read_only:false; response_revision:131; number_of_response:1; }","duration":"158.205007ms","start":"2026-04-22T00:22:28.071089Z","end":"2026-04-22T00:22:28.229294Z","steps":["trace[809935578] 'process raft request' (duration: 157.574327ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:28.415779Z","caller":"traceutil/trace.go:171","msg":"trace[181306653] transaction","detail":"{read_only:false; response_revision:132; number_of_response:1; }","duration":"182.323778ms","start":"2026-04-22T00:22:28.233427Z","end":"2026-04-22T00:22:28.415751Z","steps":["trace[181306653] 'process raft request' (duration: 138.081577ms)","trace[181306653] 'compare' (duration: 43.891034ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:28.415742Z","caller":"traceutil/trace.go:171","msg":"trace[1165190452] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"179.415629ms","start":"2026-04-22T00:22:28.236274Z","end":"2026-04-22T00:22:28.41569Z","steps":["trace[1165190452] 'process raft request' (duration: 179.273982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:28.64393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.199791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:22:28.644127Z","caller":"traceutil/trace.go:171","msg":"trace[1203692011] linearizableReadLoop","detail":"{readStateIndex:141; appliedIndex:140; }","duration":"129.611003ms","start":"2026-04-22T00:22:28.514495Z","end":"2026-04-22T00:22:28.644106Z","steps":["trace[1203692011] 'read index received' (duration: 25.118458ms)","trace[1203692011] 'applied index is now lower than readState.Index' (duration: 104.469904ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:28.644291Z","caller":"traceutil/trace.go:171","msg":"trace[876262259] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"167.91635ms","start":"2026-04-22T00:22:28.476354Z","end":"2026-04-22T00:22:28.644271Z","steps":["trace[876262259] 'process raft request' (duration: 63.287689ms)","trace[876262259] 'compare' (duration: 103.880055ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:28.644442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.943319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-proxier\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:22:28.644687Z","caller":"traceutil/trace.go:171","msg":"trace[94083982] range","detail":"{range_begin:/registry/clusterroles/system:node-proxier; range_end:; response_count:0; response_revision:136; }","duration":"130.202761ms","start":"2026-04-22T00:22:28.514446Z","end":"2026-04-22T00:22:28.644648Z","steps":["trace[94083982] 'agreement among raft nodes before linearized reading' (duration: 129.883806ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:32.453777Z","caller":"traceutil/trace.go:171","msg":"trace[940863244] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:326; }","duration":"168.027651ms","start":"2026-04-22T00:22:32.285733Z","end":"2026-04-22T00:22:32.45376Z","steps":["trace[940863244] 'read index received' (duration: 151.719549ms)","trace[940863244] 'applied index is now lower than readState.Index' (duration: 16.307552ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:32.453875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.123706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-04-22T00:22:32.453897Z","caller":"traceutil/trace.go:171","msg":"trace[1634214633] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:317; }","duration":"168.157598ms","start":"2026-04-22T00:22:32.285729Z","end":"2026-04-22T00:22:32.453887Z","steps":["trace[1634214633] 'agreement among raft nodes before linearized reading' (duration: 168.104665ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:32.453885Z","caller":"traceutil/trace.go:171","msg":"trace[1328266641] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"201.568197ms","start":"2026-04-22T00:22:32.252281Z","end":"2026-04-22T00:22:32.453849Z","steps":["trace[1328266641] 'process raft request' (duration: 185.182052ms)","trace[1328266641] 'compare' (duration: 16.191496ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:33.691567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.699766ms","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-22T00:22:33.691651Z","caller":"traceutil/trace.go:171","msg":"trace[551310954] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:329; }","duration":"104.825171ms","start":"2026-04-22T00:22:33.586808Z","end":"2026-04-22T00:22:33.691633Z","steps":["trace[551310954] 'range keys from in-memory index tree' (duration: 104.612132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:34.401281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.053831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-22T00:22:34.40144Z","caller":"traceutil/trace.go:171","msg":"trace[1390427438] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:339; }","duration":"165.286691ms","start":"2026-04-22T00:22:34.236133Z","end":"2026-04-22T00:22:34.40142Z","steps":["trace[1390427438] 'range keys from in-memory index tree' (duration: 164.886083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:34.401422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.973509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-04-22T00:22:34.401649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.756951ms","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-22T00:22:34.401699Z","caller":"traceutil/trace.go:171","msg":"trace[855315321] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:339; }","duration":"215.267792ms","start":"2026-04-22T00:22:34.186405Z","end":"2026-04-22T00:22:34.401673Z","steps":["trace[855315321] 'range keys from in-memory index tree' (duration: 214.864043ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:34.401707Z","caller":"traceutil/trace.go:171","msg":"trace[599202108] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:339; }","duration":"115.838075ms","start":"2026-04-22T00:22:34.285844Z","end":"2026-04-22T00:22:34.401682Z","steps":["trace[599202108] 'range keys from in-memory index tree' (duration: 115.660767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:35.052752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.996508ms","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-22T00:22:35.054117Z","caller":"traceutil/trace.go:171","msg":"trace[1020791073] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:343; }","duration":"267.391209ms","start":"2026-04-22T00:22:34.786675Z","end":"2026-04-22T00:22:35.054067Z","steps":["trace[1020791073] 'range keys from in-memory index tree' (duration: 265.832091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:35.052802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.599769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-04-22T00:22:35.05463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.157006ms","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-22T00:22:35.054752Z","caller":"traceutil/trace.go:171","msg":"trace[1041822113] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:343; }","duration":"317.886312ms","start":"2026-04-22T00:22:34.736822Z","end":"2026-04-22T00:22:35.054709Z","steps":["trace[1041822113] 'range keys from in-memory index tree' (duration: 316.02756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:35.054821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:34.736798Z","time spent":"317.985206ms","remote":"127.0.0.1:42906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-04-22T00:22:35.055006Z","caller":"traceutil/trace.go:171","msg":"trace[1492130152] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:343; }","duration":"117.839288ms","start":"2026-04-22T00:22:34.937122Z","end":"2026-04-22T00:22:35.054961Z","steps":["trace[1492130152] 'range keys from in-memory index tree' (duration: 115.482345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:35.052911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.525427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-22T00:22:35.055498Z","caller":"traceutil/trace.go:171","msg":"trace[1774446913] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:343; }","duration":"368.040518ms","start":"2026-04-22T00:22:34.687318Z","end":"2026-04-22T00:22:35.055358Z","steps":["trace[1774446913] 'range keys from in-memory index tree' (duration: 365.355251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:35.05566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:34.687279Z","time spent":"368.318971ms","remote":"127.0.0.1:42906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-04-22T00:22:35.05573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.157776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-22T00:22:35.055817Z","caller":"traceutil/trace.go:171","msg":"trace[1710874069] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:343; }","duration":"219.24628ms","start":"2026-04-22T00:22:34.83655Z","end":"2026-04-22T00:22:35.055796Z","steps":["trace[1710874069] 'range keys from in-memory index tree' (duration: 219.0084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:35.052824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.908691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-22T00:22:35.055985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.386047ms","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-22T00:22:35.056066Z","caller":"traceutil/trace.go:171","msg":"trace[1197767585] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:343; }","duration":"169.501812ms","start":"2026-04-22T00:22:34.886534Z","end":"2026-04-22T00:22:35.056035Z","steps":["trace[1197767585] 'range keys from in-memory index tree' (duration: 169.298043ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:35.056149Z","caller":"traceutil/trace.go:171","msg":"trace[1689814161] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:343; }","duration":"418.300119ms","start":"2026-04-22T00:22:34.637834Z","end":"2026-04-22T00:22:35.056134Z","steps":["trace[1689814161] 'range keys from in-memory index tree' (duration: 414.759424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:35.056201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:34.637799Z","time spent":"418.390764ms","remote":"127.0.0.1:42906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-04-22T00:22:35.474125Z","caller":"traceutil/trace.go:171","msg":"trace[1905156635] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"408.329693ms","start":"2026-04-22T00:22:35.065767Z","end":"2026-04-22T00:22:35.474097Z","steps":["trace[1905156635] 'process raft request' (duration: 408.087462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:35.474327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:35.065748Z","time spent":"408.48536ms","remote":"127.0.0.1:42800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:35.661765Z","caller":"traceutil/trace.go:171","msg":"trace[612707128] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"595.232417ms","start":"2026-04-22T00:22:35.066509Z","end":"2026-04-22T00:22:35.661741Z","steps":["trace[612707128] 'process raft request' (duration: 595.138483ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:35.661833Z","caller":"traceutil/trace.go:171","msg":"trace[782573169] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:353; }","duration":"574.145451ms","start":"2026-04-22T00:22:35.087676Z","end":"2026-04-22T00:22:35.661822Z","steps":["trace[782573169] 'read index received' (duration: 386.282135ms)","trace[782573169] 'applied index is now lower than readState.Index' (duration: 187.862636ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:35.661879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:35.066486Z","time spent":"595.343972ms","remote":"127.0.0.1:42864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:22:35.66191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.237296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-22T00:22:35.661932Z","caller":"traceutil/trace.go:171","msg":"trace[2006467772] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:353; }","duration":"574.353001ms","start":"2026-04-22T00:22:35.087571Z","end":"2026-04-22T00:22:35.661924Z","steps":["trace[2006467772] 'agreement among raft nodes before linearized reading' (duration: 574.287738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:35.661952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:35.087526Z","time spent":"574.421634ms","remote":"127.0.0.1:42906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-04-22T00:22:35.662018Z","caller":"traceutil/trace.go:171","msg":"trace[774014795] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"595.871235ms","start":"2026-04-22T00:22:35.066127Z","end":"2026-04-22T00:22:35.661998Z","steps":["trace[774014795] 'process raft request' (duration: 583.168647ms)","trace[774014795] 'compare' (duration: 12.199046ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:35.662109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:35.066117Z","time spent":"595.96139ms","remote":"127.0.0.1:42752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:22:35.662267Z","caller":"traceutil/trace.go:171","msg":"trace[993707069] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"593.665589ms","start":"2026-04-22T00:22:35.068596Z","end":"2026-04-22T00:22:35.662262Z","steps":["trace[993707069] 'process raft request' (duration: 593.184607ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:35.662266Z","caller":"traceutil/trace.go:171","msg":"trace[1029234599] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"593.731691ms","start":"2026-04-22T00:22:35.068378Z","end":"2026-04-22T00:22:35.66211Z","steps":["trace[1029234599] 'process raft request' (duration: 593.349784ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:35.662416Z","caller":"traceutil/trace.go:171","msg":"trace[490033984] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"593.451979ms","start":"2026-04-22T00:22:35.068959Z","end":"2026-04-22T00:22:35.662411Z","steps":["trace[490033984] 'process raft request' (duration: 592.842522ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:35.662028Z","caller":"traceutil/trace.go:171","msg":"trace[198483195] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"595.74081ms","start":"2026-04-22T00:22:35.066276Z","end":"2026-04-22T00:22:35.662017Z","steps":["trace[198483195] 'process raft request' (duration: 595.335872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:35.662554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:35.068952Z","time spent":"593.48073ms","remote":"127.0.0.1:43046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:22:35.662593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:35.066252Z","time spent":"596.267483ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:22:35.66266Z","caller":"traceutil/trace.go:171","msg":"trace[1040388525] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"595.53348ms","start":"2026-04-22T00:22:35.067102Z","end":"2026-04-22T00:22:35.662635Z","steps":["trace[1040388525] 'process raft request' (duration: 594.571018ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:35.66269Z","caller":"traceutil/trace.go:171","msg":"trace[307540058] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"595.425805ms","start":"2026-04-22T00:22:35.067214Z","end":"2026-04-22T00:22:35.66264Z","steps":["trace[307540058] 'process raft request' (duration: 594.491544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:35.662777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:35.067197Z","time spent":"595.52905ms","remote":"127.0.0.1:42974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:22:35.662803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:35.067094Z","time spent":"595.631895ms","remote":"127.0.0.1:42966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:35.662244Z","caller":"traceutil/trace.go:171","msg":"trace[1670316854] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"593.824705ms","start":"2026-04-22T00:22:35.068411Z","end":"2026-04-22T00:22:35.662236Z","steps":["trace[1670316854] 'process raft request' (duration: 593.341754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:35.663124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:35.068404Z","time spent":"594.656502ms","remote":"127.0.0.1:43046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:22:35.662392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:35.06859Z","time spent":"593.69524ms","remote":"127.0.0.1:43046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:22:35.662491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:35.068371Z","time spent":"594.056466ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:35.948327Z","caller":"traceutil/trace.go:171","msg":"trace[1775039946] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"276.030536ms","start":"2026-04-22T00:22:35.672262Z","end":"2026-04-22T00:22:35.948292Z","steps":["trace[1775039946] 'process raft request' (duration: 251.926908ms)","trace[1775039946] 'compare' (duration: 23.831576ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:35.950806Z","caller":"traceutil/trace.go:171","msg":"trace[1885310263] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"278.240903ms","start":"2026-04-22T00:22:35.672533Z","end":"2026-04-22T00:22:35.950774Z","steps":["trace[1885310263] 'process raft request' (duration: 277.95519ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:35.950927Z","caller":"traceutil/trace.go:171","msg":"trace[315480084] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"278.208501ms","start":"2026-04-22T00:22:35.672701Z","end":"2026-04-22T00:22:35.95091Z","steps":["trace[315480084] 'process raft request' (duration: 277.833974ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:35.951024Z","caller":"traceutil/trace.go:171","msg":"trace[2073470863] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"278.012643ms","start":"2026-04-22T00:22:35.673006Z","end":"2026-04-22T00:22:35.951018Z","steps":["trace[2073470863] 'process raft request' (duration: 277.549772ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:35.951131Z","caller":"traceutil/trace.go:171","msg":"trace[1543365460] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"278.684452ms","start":"2026-04-22T00:22:35.672433Z","end":"2026-04-22T00:22:35.951118Z","steps":["trace[1543365460] 'process raft request' (duration: 277.965261ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:38.118257Z","caller":"traceutil/trace.go:171","msg":"trace[243789108] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"135.448623ms","start":"2026-04-22T00:22:37.982783Z","end":"2026-04-22T00:22:38.118232Z","steps":["trace[243789108] 'process raft request' (duration: 135.272335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:38.821198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.989828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:22:38.821321Z","caller":"traceutil/trace.go:171","msg":"trace[149184117] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:387; }","duration":"415.484869ms","start":"2026-04-22T00:22:38.405815Z","end":"2026-04-22T00:22:38.8213Z","steps":["trace[149184117] 'read index received' (duration: 58.853µs)","trace[149184117] 'applied index is now lower than readState.Index' (duration: 415.424526ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:38.821376Z","caller":"traceutil/trace.go:171","msg":"trace[1410231048] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"611.166603ms","start":"2026-04-22T00:22:38.210155Z","end":"2026-04-22T00:22:38.821321Z","steps":["trace[1410231048] 'process raft request' (duration: 90.948975ms)","trace[1410231048] 'compare' (duration: 519.475226ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:38.821387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.568702ms","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-22T00:22:38.821933Z","caller":"traceutil/trace.go:171","msg":"trace[1913942038] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:378; }","duration":"416.104985ms","start":"2026-04-22T00:22:38.405806Z","end":"2026-04-22T00:22:38.821911Z","steps":["trace[1913942038] 'agreement among raft nodes before linearized reading' (duration: 415.550291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:38.821927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:38.210126Z","time spent":"611.649303ms","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:39.24469Z","caller":"traceutil/trace.go:171","msg":"trace[1089955959] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"411.235765ms","start":"2026-04-22T00:22:38.833422Z","end":"2026-04-22T00:22:39.244658Z","steps":["trace[1089955959] 'process raft request' (duration: 411.078799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:39.245036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:38.833387Z","time spent":"411.383481ms","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:40.579234Z","caller":"traceutil/trace.go:171","msg":"trace[2039024701] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:394; }","duration":"164.943492ms","start":"2026-04-22T00:22:40.414259Z","end":"2026-04-22T00:22:40.579202Z","steps":["trace[2039024701] 'read index received' (duration: 164.679361ms)","trace[2039024701] 'applied index is now lower than readState.Index' (duration: 263.031µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:40.579402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.109569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:22:40.579433Z","caller":"traceutil/trace.go:171","msg":"trace[1140577094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:384; }","duration":"165.206433ms","start":"2026-04-22T00:22:40.414215Z","end":"2026-04-22T00:22:40.579422Z","steps":["trace[1140577094] 'agreement among raft nodes before linearized reading' (duration: 165.11394ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:40.579615Z","caller":"traceutil/trace.go:171","msg":"trace[871775512] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"238.106633ms","start":"2026-04-22T00:22:40.341464Z","end":"2026-04-22T00:22:40.57957Z","steps":["trace[871775512] 'process raft request' (duration: 237.536269ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:43.350489Z","caller":"traceutil/trace.go:171","msg":"trace[1909761842] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"268.456846ms","start":"2026-04-22T00:22:43.08201Z","end":"2026-04-22T00:22:43.350467Z","steps":["trace[1909761842] 'process raft request' (duration: 268.323251ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:45.4704Z","caller":"traceutil/trace.go:171","msg":"trace[1564443307] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"110.851788ms","start":"2026-04-22T00:22:45.359505Z","end":"2026-04-22T00:22:45.470356Z","steps":["trace[1564443307] 'process raft request' (duration: 110.579147ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:53.341165Z","caller":"traceutil/trace.go:171","msg":"trace[1552268161] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"343.864542ms","start":"2026-04-22T00:22:52.997263Z","end":"2026-04-22T00:22:53.341128Z","steps":["trace[1552268161] 'process raft request' (duration: 343.640524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:53.341386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:52.997242Z","time spent":"344.040109ms","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:58.454935Z","caller":"traceutil/trace.go:171","msg":"trace[1584009673] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"139.56361ms","start":"2026-04-22T00:22:58.315348Z","end":"2026-04-22T00:22:58.454912Z","steps":["trace[1584009673] 'process raft request' (duration: 139.307291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:01.937235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.46995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:23:01.937445Z","caller":"traceutil/trace.go:171","msg":"trace[443778842] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"282.162907ms","start":"2026-04-22T00:23:01.655171Z","end":"2026-04-22T00:23:01.937334Z","steps":["trace[443778842] 'process raft request' (duration: 125.453599ms)","trace[443778842] 'compare' (duration: 156.346256ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:23:03.060398Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.278957215s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T00:23:03.060946Z","caller":"traceutil/trace.go:171","msg":"trace[154042397] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.324298347s","start":"2026-04-22T00:23:01.736605Z","end":"2026-04-22T00:23:03.060904Z","steps":["trace[154042397] 'process raft request' (duration: 1.324015758s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:03.061083Z","caller":"traceutil/trace.go:171","msg":"trace[953065939] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.322370023s","start":"2026-04-22T00:23:01.738678Z","end":"2026-04-22T00:23:03.061048Z","steps":["trace[953065939] 'process raft request' (duration: 1.322118045s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:03.061091Z","caller":"traceutil/trace.go:171","msg":"trace[1923388244] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.322423765s","start":"2026-04-22T00:23:01.738635Z","end":"2026-04-22T00:23:03.061059Z","steps":["trace[1923388244] 'process raft request' (duration: 1.322122786s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:03.061217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:01.738653Z","time spent":"1.322489897s","remote":"127.0.0.1:42714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:23:03.061271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:01.738623Z","time spent":"1.322534928s","remote":"127.0.0.1:42714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:23:03.061246Z","caller":"traceutil/trace.go:171","msg":"trace[1374325808] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.3110368s","start":"2026-04-22T00:23:01.750166Z","end":"2026-04-22T00:23:03.061202Z","steps":["trace[1374325808] 'process raft request' (duration: 1.310782432s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:03.06143Z","caller":"traceutil/trace.go:171","msg":"trace[1757587075] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.292985316s","start":"2026-04-22T00:23:01.768436Z","end":"2026-04-22T00:23:03.061422Z","steps":["trace[1757587075] 'process raft request' (duration: 1.292732748s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:03.061437Z","caller":"traceutil/trace.go:171","msg":"trace[1120999959] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.304782084s","start":"2026-04-22T00:23:01.756639Z","end":"2026-04-22T00:23:03.061421Z","steps":["trace[1120999959] 'process raft request' (duration: 1.30434937s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:03.061494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:01.756621Z","time spent":"1.304851557s","remote":"127.0.0.1:42714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:23:03.061529Z","caller":"traceutil/trace.go:171","msg":"trace[606601299] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.304766173s","start":"2026-04-22T00:23:01.756755Z","end":"2026-04-22T00:23:03.061521Z","steps":["trace[606601299] 'process raft request' (duration: 1.30435466s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:03.061411Z","caller":"traceutil/trace.go:171","msg":"trace[150501430] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:533; }","duration":"1.299579783s","start":"2026-04-22T00:23:01.761806Z","end":"2026-04-22T00:23:03.061386Z","steps":["trace[150501430] 'read index received' (duration: 18.869891ms)","trace[150501430] 'applied index is now lower than readState.Index' (duration: 1.280708602s)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:23:03.061496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:01.768413Z","time spent":"1.293053398s","remote":"127.0.0.1:42714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:23:03.061345Z","caller":"traceutil/trace.go:171","msg":"trace[1079754936] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.312652353s","start":"2026-04-22T00:23:01.748607Z","end":"2026-04-22T00:23:03.06126Z","steps":["trace[1079754936] 'process raft request' (duration: 1.312298901s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:03.061576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:01.756752Z","time spent":"1.304805914s","remote":"127.0.0.1:42714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:23:03.061592Z","caller":"traceutil/trace.go:171","msg":"trace[582633239] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"1.293053168s","start":"2026-04-22T00:23:01.768526Z","end":"2026-04-22T00:23:03.061579Z","steps":["trace[582633239] 'process raft request' (duration: 1.292697817s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:03.061615Z","caller":"traceutil/trace.go:171","msg":"trace[1174950890] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.282719098s","start":"2026-04-22T00:23:01.778887Z","end":"2026-04-22T00:23:03.061606Z","steps":["trace[1174950890] 'process raft request' (duration: 1.282431719s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:03.061551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299720407s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-22T00:23:03.061662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:01.778858Z","time spent":"1.28278342s","remote":"127.0.0.1:42714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:23:03.061681Z","caller":"traceutil/trace.go:171","msg":"trace[1797091515] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:528; }","duration":"1.299882613s","start":"2026-04-22T00:23:01.761788Z","end":"2026-04-22T00:23:03.061671Z","steps":["trace[1797091515] 'agreement among raft nodes before linearized reading' (duration: 1.299680476s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:03.061691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:01.768505Z","time spent":"1.293139891s","remote":"127.0.0.1:42714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:23:03.06139Z","caller":"traceutil/trace.go:171","msg":"trace[237209429] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.30467771s","start":"2026-04-22T00:23:01.756694Z","end":"2026-04-22T00:23:03.061372Z","steps":["trace[237209429] 'process raft request' (duration: 1.304378281s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:03.061766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:01.761782Z","time spent":"1.299966755s","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T00:23:03.061835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:01.756691Z","time spent":"1.305089603s","remote":"127.0.0.1:42714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:23:03.061743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:01.750145Z","time spent":"1.311302039s","remote":"127.0.0.1:42714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:23:03.061891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:01.736578Z","time spent":"1.325278919s","remote":"127.0.0.1:42714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:23:03.061769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:01.748585Z","time spent":"1.313057696s","remote":"127.0.0.1:42714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:23:03.561884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938290985237752179,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T00:23:03.755256Z","caller":"traceutil/trace.go:171","msg":"trace[644531010] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.83661703s","start":"2026-04-22T00:23:01.918619Z","end":"2026-04-22T00:23:03.755236Z","steps":["trace[644531010] 'process raft request' (duration: 1.836378433s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:03.755353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:01.918595Z","time spent":"1.836710954s","remote":"127.0.0.1:42714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:23:03.773513Z","caller":"traceutil/trace.go:171","msg":"trace[1240612749] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"700.71013ms","start":"2026-04-22T00:23:03.072778Z","end":"2026-04-22T00:23:03.773488Z","steps":["trace[1240612749] 'process raft request' (duration: 700.462392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:03.77376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:03.07276Z","time spent":"700.811943ms","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:23:03.773916Z","caller":"traceutil/trace.go:171","msg":"trace[48985038] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"696.944188ms","start":"2026-04-22T00:23:03.076964Z","end":"2026-04-22T00:23:03.773908Z","steps":["trace[48985038] 'process raft request' (duration: 696.70002ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:03.773991Z","caller":"traceutil/trace.go:171","msg":"trace[1368761354] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"700.845134ms","start":"2026-04-22T00:23:03.073089Z","end":"2026-04-22T00:23:03.773934Z","steps":["trace[1368761354] 'process raft request' (duration: 700.332998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:03.774132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:03.073071Z","time spent":"701.00167ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:23:03.773992Z","caller":"traceutil/trace.go:171","msg":"trace[1919843274] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"700.815953ms","start":"2026-04-22T00:23:03.073148Z","end":"2026-04-22T00:23:03.773964Z","steps":["trace[1919843274] 'process raft request' (duration: 700.360248ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:03.774138Z","caller":"traceutil/trace.go:171","msg":"trace[697284325] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"701.01941ms","start":"2026-04-22T00:23:03.073107Z","end":"2026-04-22T00:23:03.774126Z","steps":["trace[697284325] 'process raft request' (duration: 700.365379ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:03.774429Z","caller":"traceutil/trace.go:171","msg":"trace[597526438] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"696.606317ms","start":"2026-04-22T00:23:03.07767Z","end":"2026-04-22T00:23:03.774277Z","steps":["trace[597526438] 'process raft request' (duration: 696.08117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:03.774504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:03.073095Z","time spent":"701.376151ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:23:03.774074Z","caller":"traceutil/trace.go:171","msg":"trace[986939990] transaction","detail":"{read_only:false; number_of_response:1; response_revision:534; }","duration":"698.55548ms","start":"2026-04-22T00:23:03.075349Z","end":"2026-04-22T00:23:03.773905Z","steps":["trace[986939990] 'process raft request' (duration: 698.283351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:03.774388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:03.073142Z","time spent":"701.201585ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:23:03.774603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:03.075331Z","time spent":"699.238593ms","remote":"127.0.0.1:42714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6936,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:23:03.774017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:03.076955Z","time spent":"696.98511ms","remote":"127.0.0.1:42714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:23:03.774256Z","caller":"traceutil/trace.go:171","msg":"trace[684142090] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"700.316327ms","start":"2026-04-22T00:23:03.073929Z","end":"2026-04-22T00:23:03.774245Z","steps":["trace[684142090] 'process raft request' (duration: 699.607104ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:03.774504Z","caller":"traceutil/trace.go:171","msg":"trace[1500300811] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"691.009937ms","start":"2026-04-22T00:23:03.083483Z","end":"2026-04-22T00:23:03.774493Z","steps":["trace[1500300811] 'process raft request' (duration: 690.49117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:03.775058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:03.08346Z","time spent":"691.571695ms","remote":"127.0.0.1:42714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:23:03.774732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:03.073921Z","time spent":"700.776442ms","remote":"127.0.0.1:42714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:23:03.774435Z","caller":"traceutil/trace.go:171","msg":"trace[2055893989] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"694.132828ms","start":"2026-04-22T00:23:03.080289Z","end":"2026-04-22T00:23:03.774422Z","steps":["trace[2055893989] 'process raft request' (duration: 693.534909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:03.775221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:03.08028Z","time spent":"694.8441ms","remote":"127.0.0.1:42714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:23:03.774304Z","caller":"traceutil/trace.go:171","msg":"trace[2044959636] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"695.43239ms","start":"2026-04-22T00:23:03.078864Z","end":"2026-04-22T00:23:03.774296Z","steps":["trace[2044959636] 'process raft request' (duration: 694.922183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:03.775443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:03.078857Z","time spent":"696.542425ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:23:03.774862Z","caller":"traceutil/trace.go:171","msg":"trace[1016325679] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:545; }","duration":"713.279034ms","start":"2026-04-22T00:23:03.061569Z","end":"2026-04-22T00:23:03.774848Z","steps":["trace[1016325679] 'read index received' (duration: 693.500827ms)","trace[1016325679] 'applied index is now lower than readState.Index' (duration: 19.777427ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:23:03.774902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.359268687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:03.776137Z","caller":"traceutil/trace.go:171","msg":"trace[1998091791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"1.360443136s","start":"2026-04-22T00:23:02.415588Z","end":"2026-04-22T00:23:03.776031Z","steps":["trace[1998091791] 'agreement among raft nodes before linearized reading' (duration: 1.359292329s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:03.776305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:02.415567Z","time spent":"1.360662282s","remote":"127.0.0.1:42654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T00:23:03.774761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:03.077663Z","time spent":"696.97048ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:23:03.777767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.897743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-22T00:23:03.777812Z","caller":"traceutil/trace.go:171","msg":"trace[854720562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"252.981056ms","start":"2026-04-22T00:23:03.52482Z","end":"2026-04-22T00:23:03.777801Z","steps":["trace[854720562] 'agreement among raft nodes before linearized reading' (duration: 252.864002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:03.774974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30839673s","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-22T00:23:03.778013Z","caller":"traceutil/trace.go:171","msg":"trace[757367075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:540; }","duration":"1.311427498s","start":"2026-04-22T00:23:02.466573Z","end":"2026-04-22T00:23:03.778001Z","steps":["trace[757367075] 'agreement among raft nodes before linearized reading' (duration: 1.308341479s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:03.778093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:02.466519Z","time spent":"1.311562543s","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T00:23:03.774988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270402423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-22T00:23:03.778149Z","caller":"traceutil/trace.go:171","msg":"trace[49022064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:540; }","duration":"1.273596216s","start":"2026-04-22T00:23:02.50454Z","end":"2026-04-22T00:23:03.778136Z","steps":["trace[49022064] 'agreement among raft nodes before linearized reading' (duration: 1.270370151s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:03.774961Z","caller":"traceutil/trace.go:171","msg":"trace[110056839] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"689.625533ms","start":"2026-04-22T00:23:03.085323Z","end":"2026-04-22T00:23:03.774949Z","steps":["trace[110056839] 'process raft request' (duration: 689.124967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:03.778195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:02.504522Z","time spent":"1.273661408s","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T00:23:03.778232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:03.085314Z","time spent":"692.875247ms","remote":"127.0.0.1:42714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:23:04.106327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.116671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:23:04.106585Z","caller":"traceutil/trace.go:171","msg":"trace[457422608] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"148.613047ms","start":"2026-04-22T00:23:03.957952Z","end":"2026-04-22T00:23:04.106565Z","steps":["trace[457422608] 'process raft request' (duration: 148.525074ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:04.107392Z","caller":"traceutil/trace.go:171","msg":"trace[1630590771] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"149.746924ms","start":"2026-04-22T00:23:03.957571Z","end":"2026-04-22T00:23:04.107318Z","steps":["trace[1630590771] 'process raft request' (duration: 36.547669ms)","trace[1630590771] 'compare' (duration: 111.970795ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:04.107743Z","caller":"traceutil/trace.go:171","msg":"trace[276217888] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"127.402573ms","start":"2026-04-22T00:23:03.980323Z","end":"2026-04-22T00:23:04.107726Z","steps":["trace[276217888] 'process raft request' (duration: 127.247758ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:04.503437Z","caller":"traceutil/trace.go:171","msg":"trace[1174174269] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"208.487923ms","start":"2026-04-22T00:23:04.294922Z","end":"2026-04-22T00:23:04.50341Z","steps":["trace[1174174269] 'read index received' (duration: 114.99919ms)","trace[1174174269] 'applied index is now lower than readState.Index' (duration: 93.487113ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:04.503743Z","caller":"traceutil/trace.go:171","msg":"trace[634191307] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"210.085174ms","start":"2026-04-22T00:23:04.293643Z","end":"2026-04-22T00:23:04.503728Z","steps":["trace[634191307] 'process raft request' (duration: 116.25033ms)","trace[634191307] 'compare' (duration: 93.369899ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:23:04.504079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.132913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:04.504184Z","caller":"traceutil/trace.go:171","msg":"trace[12796954] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:573; }","duration":"209.254777ms","start":"2026-04-22T00:23:04.294887Z","end":"2026-04-22T00:23:04.504141Z","steps":["trace[12796954] 'agreement among raft nodes before linearized reading' (duration: 209.113533ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:04.504247Z","caller":"traceutil/trace.go:171","msg":"trace[1624736935] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"163.078514ms","start":"2026-04-22T00:23:04.341145Z","end":"2026-04-22T00:23:04.504223Z","steps":["trace[1624736935] 'process raft request' (duration: 162.862047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:04.523311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.306155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:04.523373Z","caller":"traceutil/trace.go:171","msg":"trace[883472440] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:575; }","duration":"228.400179ms","start":"2026-04-22T00:23:04.294957Z","end":"2026-04-22T00:23:04.523357Z","steps":["trace[883472440] 'agreement among raft nodes before linearized reading' (duration: 228.300045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:04.52405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.631602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:04.524073Z","caller":"traceutil/trace.go:171","msg":"trace[1794070002] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:575; }","duration":"121.690143ms","start":"2026-04-22T00:23:04.402378Z","end":"2026-04-22T00:23:04.524068Z","steps":["trace[1794070002] 'agreement among raft nodes before linearized reading' (duration: 121.655142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:04.524228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.390145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:04.524247Z","caller":"traceutil/trace.go:171","msg":"trace[1571866558] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:575; }","duration":"122.417857ms","start":"2026-04-22T00:23:04.401824Z","end":"2026-04-22T00:23:04.524242Z","steps":["trace[1571866558] 'agreement among raft nodes before linearized reading' (duration: 122.323354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:04.524342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.551491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:04.524355Z","caller":"traceutil/trace.go:171","msg":"trace[180067438] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:575; }","duration":"122.577931ms","start":"2026-04-22T00:23:04.401774Z","end":"2026-04-22T00:23:04.524352Z","steps":["trace[180067438] 'agreement among raft nodes before linearized reading' (duration: 122.556621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:04.524415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.373803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:04.524427Z","caller":"traceutil/trace.go:171","msg":"trace[1981904335] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:575; }","duration":"147.411004ms","start":"2026-04-22T00:23:04.377012Z","end":"2026-04-22T00:23:04.524423Z","steps":["trace[1981904335] 'agreement among raft nodes before linearized reading' (duration: 147.388353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:04.52457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.040704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:04.524585Z","caller":"traceutil/trace.go:171","msg":"trace[2105126849] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:575; }","duration":"148.095966ms","start":"2026-04-22T00:23:04.376485Z","end":"2026-04-22T00:23:04.524581Z","steps":["trace[2105126849] 'agreement among raft nodes before linearized reading' (duration: 148.057614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:04.524657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.176779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:04.52467Z","caller":"traceutil/trace.go:171","msg":"trace[111266501] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:575; }","duration":"148.190949ms","start":"2026-04-22T00:23:04.376474Z","end":"2026-04-22T00:23:04.524665Z","steps":["trace[111266501] 'agreement among raft nodes before linearized reading' (duration: 148.170609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:04.524727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.061574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:04.524739Z","caller":"traceutil/trace.go:171","msg":"trace[1504248857] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:575; }","duration":"174.101986ms","start":"2026-04-22T00:23:04.350633Z","end":"2026-04-22T00:23:04.524735Z","steps":["trace[1504248857] 'agreement among raft nodes before linearized reading' (duration: 174.081725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:04.524794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.275831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:04.524806Z","caller":"traceutil/trace.go:171","msg":"trace[807526609] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:575; }","duration":"174.289022ms","start":"2026-04-22T00:23:04.350513Z","end":"2026-04-22T00:23:04.524802Z","steps":["trace[807526609] 'agreement among raft nodes before linearized reading' (duration: 174.268581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:04.524943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.039101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:04.524997Z","caller":"traceutil/trace.go:171","msg":"trace[88548613] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:575; }","duration":"204.132974ms","start":"2026-04-22T00:23:04.320851Z","end":"2026-04-22T00:23:04.524984Z","steps":["trace[88548613] 'agreement among raft nodes before linearized reading' (duration: 204.02634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:04.525145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.643852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:04.525164Z","caller":"traceutil/trace.go:171","msg":"trace[1092902152] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:575; }","duration":"205.690713ms","start":"2026-04-22T00:23:04.319467Z","end":"2026-04-22T00:23:04.525158Z","steps":["trace[1092902152] 'agreement among raft nodes before linearized reading' (duration: 205.658582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:04.525239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.958482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:04.525265Z","caller":"traceutil/trace.go:171","msg":"trace[285347975] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:575; }","duration":"206.015054ms","start":"2026-04-22T00:23:04.319243Z","end":"2026-04-22T00:23:04.525258Z","steps":["trace[285347975] 'agreement among raft nodes before linearized reading' (duration: 205.973383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:04.525354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.283798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:04.525372Z","caller":"traceutil/trace.go:171","msg":"trace[386885833] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:575; }","duration":"230.31376ms","start":"2026-04-22T00:23:04.295053Z","end":"2026-04-22T00:23:04.525367Z","steps":["trace[386885833] 'agreement among raft nodes before linearized reading' (duration: 230.285889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:10.127978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.839209ms","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-22T00:23:10.128065Z","caller":"traceutil/trace.go:171","msg":"trace[622075695] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:619; }","duration":"322.956843ms","start":"2026-04-22T00:23:09.805088Z","end":"2026-04-22T00:23:10.128045Z","steps":["trace[622075695] 'range keys from in-memory index tree' (duration: 322.687954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:10.128162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:09.805075Z","time spent":"323.069906ms","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T00:23:10.128204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.564114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:23:10.128309Z","caller":"traceutil/trace.go:171","msg":"trace[871789780] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"290.622281ms","start":"2026-04-22T00:23:09.837668Z","end":"2026-04-22T00:23:10.12829Z","steps":["trace[871789780] 'read index received' (duration: 161.839291ms)","trace[871789780] 'applied index is now lower than readState.Index' (duration: 128.78166ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:10.128412Z","caller":"traceutil/trace.go:171","msg":"trace[2009193714] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"322.657234ms","start":"2026-04-22T00:23:09.805739Z","end":"2026-04-22T00:23:10.128396Z","steps":["trace[2009193714] 'process raft request' (duration: 193.78993ms)","trace[2009193714] 'compare' (duration: 128.140022ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:23:10.128461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:09.805731Z","time spent":"322.705995ms","remote":"127.0.0.1:43050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:23:10.128807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.131656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-22T00:23:10.128852Z","caller":"traceutil/trace.go:171","msg":"trace[985409090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:620; }","duration":"291.23815ms","start":"2026-04-22T00:23:09.8376Z","end":"2026-04-22T00:23:10.128838Z","steps":["trace[985409090] 'agreement among raft nodes before linearized reading' (duration: 291.045594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:10.129039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.363271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-22T00:23:10.129087Z","caller":"traceutil/trace.go:171","msg":"trace[717285430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:620; }","duration":"281.416952ms","start":"2026-04-22T00:23:09.847657Z","end":"2026-04-22T00:23:10.129073Z","steps":["trace[717285430] 'agreement among raft nodes before linearized reading' (duration: 281.301778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:10.129031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.794383ms","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-22T00:23:10.129245Z","caller":"traceutil/trace.go:171","msg":"trace[1671106853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:620; }","duration":"282.01563ms","start":"2026-04-22T00:23:09.847206Z","end":"2026-04-22T00:23:10.129221Z","steps":["trace[1671106853] 'agreement among raft nodes before linearized reading' (duration: 281.561107ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:10.38735Z","caller":"traceutil/trace.go:171","msg":"trace[1620919628] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"251.263689ms","start":"2026-04-22T00:23:10.136073Z","end":"2026-04-22T00:23:10.387337Z","steps":["trace[1620919628] 'process raft request' (duration: 251.153356ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:10.387412Z","caller":"traceutil/trace.go:171","msg":"trace[901244867] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:641; }","duration":"248.170366ms","start":"2026-04-22T00:23:10.139233Z","end":"2026-04-22T00:23:10.387403Z","steps":["trace[901244867] 'read index received' (duration: 32.427185ms)","trace[901244867] 'applied index is now lower than readState.Index' (duration: 215.741811ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:23:10.387484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.233558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:10.387524Z","caller":"traceutil/trace.go:171","msg":"trace[283692219] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:624; }","duration":"248.284839ms","start":"2026-04-22T00:23:10.139226Z","end":"2026-04-22T00:23:10.387511Z","steps":["trace[283692219] 'agreement among raft nodes before linearized reading' (duration: 248.211457ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:10.387559Z","caller":"traceutil/trace.go:171","msg":"trace[1280255842] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"251.025182ms","start":"2026-04-22T00:23:10.136496Z","end":"2026-04-22T00:23:10.387521Z","steps":["trace[1280255842] 'process raft request' (duration: 250.809755ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:10.387329Z","caller":"traceutil/trace.go:171","msg":"trace[593317571] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"251.30187ms","start":"2026-04-22T00:23:10.135996Z","end":"2026-04-22T00:23:10.387298Z","steps":["trace[593317571] 'process raft request' (duration: 236.618319ms)","trace[593317571] 'compare' (duration: 14.345721ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:11.232414Z","caller":"traceutil/trace.go:171","msg":"trace[1760356269] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"457.047664ms","start":"2026-04-22T00:23:10.775337Z","end":"2026-04-22T00:23:11.232385Z","steps":["trace[1760356269] 'process raft request' (duration: 456.405655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:11.232565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:10.775311Z","time spent":"457.176667ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:23:12.141249Z","caller":"traceutil/trace.go:171","msg":"trace[739930791] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"361.231106ms","start":"2026-04-22T00:23:11.779992Z","end":"2026-04-22T00:23:12.141223Z","steps":["trace[739930791] 'process raft request' (duration: 360.615908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:12.141392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:11.779956Z","time spent":"361.36027ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:23:12.770652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938290985237752374,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T00:23:12.935279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.480868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:23:12.935361Z","caller":"traceutil/trace.go:171","msg":"trace[1180951033] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"757.436942ms","start":"2026-04-22T00:23:12.177907Z","end":"2026-04-22T00:23:12.935344Z","steps":["trace[1180951033] 'process raft request' (duration: 46.798419ms)","trace[1180951033] 'compare' (duration: 710.379556ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:23:12.935402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:12.177895Z","time spent":"757.487963ms","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:23:12.952231Z","caller":"traceutil/trace.go:171","msg":"trace[447577293] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"682.35043ms","start":"2026-04-22T00:23:12.269862Z","end":"2026-04-22T00:23:12.952212Z","steps":["trace[447577293] 'read index received' (duration: 682.190386ms)","trace[447577293] 'applied index is now lower than readState.Index' (duration: 159.154µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:12.95237Z","caller":"traceutil/trace.go:171","msg":"trace[70400697] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"755.948188ms","start":"2026-04-22T00:23:12.196364Z","end":"2026-04-22T00:23:12.952313Z","steps":["trace[70400697] 'process raft request' (duration: 755.759873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:12.952547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.648459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-szd95\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-22T00:23:12.952605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.586355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:12.953547Z","caller":"traceutil/trace.go:171","msg":"trace[1693307462] range","detail":"{range_begin:/registry/pods/kube-system/cilium-szd95; range_end:; response_count:1; response_revision:633; }","duration":"683.71298ms","start":"2026-04-22T00:23:12.269821Z","end":"2026-04-22T00:23:12.953534Z","steps":["trace[1693307462] 'agreement among raft nodes before linearized reading' (duration: 682.480034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:12.953587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:12.269804Z","time spent":"683.772443ms","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-szd95\" "} {"level":"info","ts":"2026-04-22T00:23:12.953583Z","caller":"traceutil/trace.go:171","msg":"trace[342914181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"539.600505ms","start":"2026-04-22T00:23:12.413962Z","end":"2026-04-22T00:23:12.953562Z","steps":["trace[342914181] 'agreement among raft nodes before linearized reading' (duration: 538.523373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:12.953537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.488762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-22T00:23:12.953637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:12.413953Z","time spent":"539.673517ms","remote":"127.0.0.1:42664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T00:23:12.953666Z","caller":"traceutil/trace.go:171","msg":"trace[1191539880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"560.575124ms","start":"2026-04-22T00:23:12.393067Z","end":"2026-04-22T00:23:12.953642Z","steps":["trace[1191539880] 'agreement among raft nodes before linearized reading' (duration: 559.348597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:12.953682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:12.196344Z","time spent":"756.149715ms","remote":"127.0.0.1:42940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:23:12.953736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:12.393043Z","time spent":"560.677666ms","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T00:23:12.954194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.893532ms","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-22T00:23:12.954234Z","caller":"traceutil/trace.go:171","msg":"trace[1821744135] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"561.981865ms","start":"2026-04-22T00:23:12.392241Z","end":"2026-04-22T00:23:12.954223Z","steps":["trace[1821744135] 'agreement among raft nodes before linearized reading' (duration: 560.167141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:12.954264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:12.392219Z","time spent":"562.037897ms","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T00:23:12.954308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.153751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-22T00:23:12.954417Z","caller":"traceutil/trace.go:171","msg":"trace[2124458314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"561.324516ms","start":"2026-04-22T00:23:12.393067Z","end":"2026-04-22T00:23:12.954391Z","steps":["trace[2124458314] 'agreement among raft nodes before linearized reading' (duration: 559.384138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:12.954471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:12.393043Z","time spent":"561.420448ms","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T00:23:14.80857Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.801367547s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-22T00:23:14.941421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.280917ms","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-22T00:23:14.941567Z","caller":"traceutil/trace.go:171","msg":"trace[1000052424] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:634; }","duration":"516.433261ms","start":"2026-04-22T00:23:14.425109Z","end":"2026-04-22T00:23:14.941542Z","steps":["trace[1000052424] 'range keys from in-memory index tree' (duration: 516.264886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:14.942206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.25463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:23:14.942605Z","caller":"traceutil/trace.go:171","msg":"trace[810576978] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.981811516s","start":"2026-04-22T00:23:12.960774Z","end":"2026-04-22T00:23:14.942586Z","steps":["trace[810576978] 'process raft request' (duration: 1.848100572s)","trace[810576978] 'compare' (duration: 132.715675ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:23:14.942672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:12.960712Z","time spent":"1.981929169s","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:23:14.942822Z","caller":"traceutil/trace.go:171","msg":"trace[1522172523] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.981469606s","start":"2026-04-22T00:23:12.961344Z","end":"2026-04-22T00:23:14.942813Z","steps":["trace[1522172523] 'process raft request' (duration: 1.980946631s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:14.942861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:12.961321Z","time spent":"1.981517137s","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:23:14.943029Z","caller":"traceutil/trace.go:171","msg":"trace[1059376237] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:654; }","duration":"1.980850068s","start":"2026-04-22T00:23:12.962119Z","end":"2026-04-22T00:23:14.942969Z","steps":["trace[1059376237] 'read index received' (duration: 45.068096ms)","trace[1059376237] 'applied index is now lower than readState.Index' (duration: 1.935773071s)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:14.943131Z","caller":"traceutil/trace.go:171","msg":"trace[1906937902] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"677.967668ms","start":"2026-04-22T00:23:14.265148Z","end":"2026-04-22T00:23:14.943116Z","steps":["trace[1906937902] 'process raft request' (duration: 677.232927ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:14.943144Z","caller":"traceutil/trace.go:171","msg":"trace[825817846] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.976592232s","start":"2026-04-22T00:23:12.966538Z","end":"2026-04-22T00:23:14.943131Z","steps":["trace[825817846] 'process raft request' (duration: 1.975796389s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:14.9432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:14.265123Z","time spent":"678.04216ms","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:23:14.943313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:12.966526Z","time spent":"1.976661274s","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:23:14.964647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.549863516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:14.964729Z","caller":"traceutil/trace.go:171","msg":"trace[776963051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"1.550011671s","start":"2026-04-22T00:23:13.414698Z","end":"2026-04-22T00:23:14.96471Z","steps":["trace[776963051] 'agreement among raft nodes before linearized reading' (duration: 1.549874247s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:14.964784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.002655928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-22T00:23:14.964833Z","caller":"traceutil/trace.go:171","msg":"trace[246958054] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:638; }","duration":"2.00274622s","start":"2026-04-22T00:23:12.962073Z","end":"2026-04-22T00:23:14.964819Z","steps":["trace[246958054] 'agreement among raft nodes before linearized reading' (duration: 1.981358632s)","trace[246958054] 'range keys from in-memory index tree' (duration: 21.122411ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:23:14.964772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:13.414674Z","time spent":"1.550088202s","remote":"127.0.0.1:42654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T00:23:14.964866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:12.962063Z","time spent":"2.002795762s","remote":"127.0.0.1:42906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-04-22T00:23:15.411472Z","caller":"traceutil/trace.go:171","msg":"trace[1590498928] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"130.255408ms","start":"2026-04-22T00:23:15.281187Z","end":"2026-04-22T00:23:15.411442Z","steps":["trace[1590498928] 'process raft request' (duration: 130.110193ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:15.422437Z","caller":"traceutil/trace.go:171","msg":"trace[746754420] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"128.524708ms","start":"2026-04-22T00:23:15.293897Z","end":"2026-04-22T00:23:15.422422Z","steps":["trace[746754420] 'process raft request' (duration: 128.428585ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:16.656952Z","caller":"traceutil/trace.go:171","msg":"trace[4695224] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"160.663174ms","start":"2026-04-22T00:23:16.496268Z","end":"2026-04-22T00:23:16.656931Z","steps":["trace[4695224] 'process raft request' (duration: 160.5361ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:19.457552Z","caller":"traceutil/trace.go:171","msg":"trace[1287504094] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"138.619139ms","start":"2026-04-22T00:23:19.318905Z","end":"2026-04-22T00:23:19.457524Z","steps":["trace[1287504094] 'process raft request' (duration: 138.450794ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:19.489095Z","caller":"traceutil/trace.go:171","msg":"trace[289796541] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"156.312927ms","start":"2026-04-22T00:23:19.332754Z","end":"2026-04-22T00:23:19.489067Z","steps":["trace[289796541] 'process raft request' (duration: 155.807613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:32.453806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123693134s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:23:32.453954Z","caller":"traceutil/trace.go:171","msg":"trace[222962481] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"1.333423902s","start":"2026-04-22T00:23:31.120499Z","end":"2026-04-22T00:23:32.453923Z","steps":["trace[222962481] 'process raft request' (duration: 209.474681ms)","trace[222962481] 'compare' (duration: 1.123551661s)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:23:32.454101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:31.120474Z","time spent":"1.333553815s","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:23:32.454227Z","caller":"traceutil/trace.go:171","msg":"trace[1758295687] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"1.276512014s","start":"2026-04-22T00:23:31.177681Z","end":"2026-04-22T00:23:32.454194Z","steps":["trace[1758295687] 'read index received' (duration: 152.189385ms)","trace[1758295687] 'applied index is now lower than readState.Index' (duration: 1.124319869s)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:32.454467Z","caller":"traceutil/trace.go:171","msg":"trace[1673948031] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"221.774589ms","start":"2026-04-22T00:23:32.232673Z","end":"2026-04-22T00:23:32.454448Z","steps":["trace[1673948031] 'process raft request' (duration: 221.228636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:32.454457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040127056s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:32.454523Z","caller":"traceutil/trace.go:171","msg":"trace[1618957722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"1.040217609s","start":"2026-04-22T00:23:31.414292Z","end":"2026-04-22T00:23:32.45451Z","steps":["trace[1618957722] 'agreement among raft nodes before linearized reading' (duration: 1.040128827s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:32.454556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:31.414273Z","time spent":"1.04027528s","remote":"127.0.0.1:42654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T00:23:32.454556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276851492s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-22T00:23:32.454556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078094525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jxm6g\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-22T00:23:32.45463Z","caller":"traceutil/trace.go:171","msg":"trace[1333027061] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jxm6g; range_end:; response_count:1; response_revision:795; }","duration":"1.078216949s","start":"2026-04-22T00:23:31.376395Z","end":"2026-04-22T00:23:32.454612Z","steps":["trace[1333027061] 'agreement among raft nodes before linearized reading' (duration: 1.078029864s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:32.454647Z","caller":"traceutil/trace.go:171","msg":"trace[472636505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:795; }","duration":"1.276971075s","start":"2026-04-22T00:23:31.177631Z","end":"2026-04-22T00:23:32.454602Z","steps":["trace[472636505] 'agreement among raft nodes before linearized reading' (duration: 1.276734739s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:32.454689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:31.376376Z","time spent":"1.078299791s","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jxm6g\" "} {"level":"warn","ts":"2026-04-22T00:23:32.4547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:23:31.177611Z","time spent":"1.277070868s","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T00:23:35.32913Z","caller":"traceutil/trace.go:171","msg":"trace[825118539] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:839; }","duration":"243.500552ms","start":"2026-04-22T00:23:35.085608Z","end":"2026-04-22T00:23:35.329109Z","steps":["trace[825118539] 'read index received' (duration: 243.280577ms)","trace[825118539] 'applied index is now lower than readState.Index' (duration: 218.925µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:35.329214Z","caller":"traceutil/trace.go:171","msg":"trace[656508060] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"243.734998ms","start":"2026-04-22T00:23:35.085459Z","end":"2026-04-22T00:23:35.329194Z","steps":["trace[656508060] 'process raft request' (duration: 243.434981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:35.329262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.618115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:35.329304Z","caller":"traceutil/trace.go:171","msg":"trace[669160750] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:812; }","duration":"243.699957ms","start":"2026-04-22T00:23:35.085593Z","end":"2026-04-22T00:23:35.329293Z","steps":["trace[669160750] 'agreement among raft nodes before linearized reading' (duration: 243.610035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:35.334441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.888347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:35.334526Z","caller":"traceutil/trace.go:171","msg":"trace[116838282] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:813; }","duration":"247.97587ms","start":"2026-04-22T00:23:35.086535Z","end":"2026-04-22T00:23:35.334511Z","steps":["trace[116838282] 'agreement among raft nodes before linearized reading' (duration: 247.837986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:35.334735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.216024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T00:23:35.334758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.505675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-22T00:23:35.334785Z","caller":"traceutil/trace.go:171","msg":"trace[1056798146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:813; }","duration":"241.555376ms","start":"2026-04-22T00:23:35.093222Z","end":"2026-04-22T00:23:35.334778Z","steps":["trace[1056798146] 'agreement among raft nodes before linearized reading' (duration: 241.464774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:35.334857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.377608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:35.334883Z","caller":"traceutil/trace.go:171","msg":"trace[303214099] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:813; }","duration":"125.424559ms","start":"2026-04-22T00:23:35.209451Z","end":"2026-04-22T00:23:35.334876Z","steps":["trace[303214099] 'agreement among raft nodes before linearized reading' (duration: 125.385618ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:35.334904Z","caller":"traceutil/trace.go:171","msg":"trace[1725551197] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:813; }","duration":"125.400229ms","start":"2026-04-22T00:23:35.209488Z","end":"2026-04-22T00:23:35.334888Z","steps":["trace[1725551197] 'agreement among raft nodes before linearized reading' (duration: 125.143762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:36.922214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.828099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:23:36.922336Z","caller":"traceutil/trace.go:171","msg":"trace[870833554] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"249.894186ms","start":"2026-04-22T00:23:36.672418Z","end":"2026-04-22T00:23:36.922312Z","steps":["trace[870833554] 'process raft request' (duration: 49.893603ms)","trace[870833554] 'compare' (duration: 199.510311ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:36.923474Z","caller":"traceutil/trace.go:171","msg":"trace[724860297] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:855; }","duration":"149.117256ms","start":"2026-04-22T00:23:36.774322Z","end":"2026-04-22T00:23:36.923439Z","steps":["trace[724860297] 'read index received' (duration: 148.971952ms)","trace[724860297] 'applied index is now lower than readState.Index' (duration: 144.594µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:23:36.923595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:36.923624Z","caller":"traceutil/trace.go:171","msg":"trace[664921329] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:828; }","duration":"149.377772ms","start":"2026-04-22T00:23:36.774239Z","end":"2026-04-22T00:23:36.923617Z","steps":["trace[664921329] 'agreement among raft nodes before linearized reading' (duration: 149.29073ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:36.923872Z","caller":"traceutil/trace.go:171","msg":"trace[787867868] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"251.010742ms","start":"2026-04-22T00:23:36.672558Z","end":"2026-04-22T00:23:36.923569Z","steps":["trace[787867868] 'process raft request' (duration: 250.787707ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:44.675608Z","caller":"traceutil/trace.go:171","msg":"trace[410154099] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"120.96441ms","start":"2026-04-22T00:23:44.554626Z","end":"2026-04-22T00:23:44.67559Z","steps":["trace[410154099] 'process raft request' (duration: 120.825047ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:44.851808Z","caller":"traceutil/trace.go:171","msg":"trace[1921332801] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:951; }","duration":"196.249833ms","start":"2026-04-22T00:23:44.655535Z","end":"2026-04-22T00:23:44.851785Z","steps":["trace[1921332801] 'read index received' (duration: 19.967274ms)","trace[1921332801] 'applied index is now lower than readState.Index' (duration: 176.281379ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:44.851906Z","caller":"traceutil/trace.go:171","msg":"trace[1662362616] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"294.279532ms","start":"2026-04-22T00:23:44.557574Z","end":"2026-04-22T00:23:44.851854Z","steps":["trace[1662362616] 'process raft request' (duration: 293.108496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:44.852031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.484979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-22T00:23:44.852148Z","caller":"traceutil/trace.go:171","msg":"trace[823252454] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:923; }","duration":"196.646542ms","start":"2026-04-22T00:23:44.65548Z","end":"2026-04-22T00:23:44.852126Z","steps":["trace[823252454] 'agreement among raft nodes before linearized reading' (duration: 196.429387ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:45.020954Z","caller":"traceutil/trace.go:171","msg":"trace[2001758043] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"112.404077ms","start":"2026-04-22T00:23:44.908519Z","end":"2026-04-22T00:23:45.020923Z","steps":["trace[2001758043] 'process raft request' (duration: 44.190043ms)","trace[2001758043] 'compare' (duration: 68.04664ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:45.024267Z","caller":"traceutil/trace.go:171","msg":"trace[1796751457] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"112.555799ms","start":"2026-04-22T00:23:44.911696Z","end":"2026-04-22T00:23:45.024252Z","steps":["trace[1796751457] 'process raft request' (duration: 112.259533ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:45.0243Z","caller":"traceutil/trace.go:171","msg":"trace[1982127598] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"115.619028ms","start":"2026-04-22T00:23:44.908653Z","end":"2026-04-22T00:23:45.024272Z","steps":["trace[1982127598] 'process raft request' (duration: 115.221449ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:50.806279Z","caller":"traceutil/trace.go:171","msg":"trace[550399681] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"102.406615ms","start":"2026-04-22T00:23:50.703842Z","end":"2026-04-22T00:23:50.806249Z","steps":["trace[550399681] 'process raft request' (duration: 102.231791ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:50.992854Z","caller":"traceutil/trace.go:171","msg":"trace[2105003981] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"105.110133ms","start":"2026-04-22T00:23:50.887709Z","end":"2026-04-22T00:23:50.992819Z","steps":["trace[2105003981] 'process raft request' (duration: 95.383356ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:51.466747Z","caller":"traceutil/trace.go:171","msg":"trace[875158744] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"286.5023ms","start":"2026-04-22T00:23:51.180188Z","end":"2026-04-22T00:23:51.46669Z","steps":["trace[875158744] 'process raft request' (duration: 286.273485ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:51.757855Z","caller":"traceutil/trace.go:171","msg":"trace[654668158] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"285.629651ms","start":"2026-04-22T00:23:51.472201Z","end":"2026-04-22T00:23:51.757831Z","steps":["trace[654668158] 'process raft request' (duration: 252.150496ms)","trace[654668158] 'compare' (duration: 33.22866ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:51.757929Z","caller":"traceutil/trace.go:171","msg":"trace[1463419067] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"285.661132ms","start":"2026-04-22T00:23:51.472235Z","end":"2026-04-22T00:23:51.757896Z","steps":["trace[1463419067] 'process raft request' (duration: 285.508689ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:51.758298Z","caller":"traceutil/trace.go:171","msg":"trace[1832019786] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1018; }","duration":"284.994288ms","start":"2026-04-22T00:23:51.473274Z","end":"2026-04-22T00:23:51.758268Z","steps":["trace[1832019786] 'read index received' (duration: 18.285465ms)","trace[1832019786] 'applied index is now lower than readState.Index' (duration: 266.707663ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:23:51.75858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.293375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-srbwp\" ","response":"range_response_count:1 size:4908"} {"level":"info","ts":"2026-04-22T00:23:51.758635Z","caller":"traceutil/trace.go:171","msg":"trace[1880916698] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-srbwp; range_end:; response_count:1; response_revision:992; }","duration":"285.412577ms","start":"2026-04-22T00:23:51.473206Z","end":"2026-04-22T00:23:51.758618Z","steps":["trace[1880916698] 'agreement among raft nodes before linearized reading' (duration: 285.202023ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:51.758692Z","caller":"traceutil/trace.go:171","msg":"trace[496245802] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"286.130882ms","start":"2026-04-22T00:23:51.472553Z","end":"2026-04-22T00:23:51.758684Z","steps":["trace[496245802] 'process raft request' (duration: 285.274694ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:51.758812Z","caller":"traceutil/trace.go:171","msg":"trace[1202967257] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"283.460056ms","start":"2026-04-22T00:23:51.47533Z","end":"2026-04-22T00:23:51.75879Z","steps":["trace[1202967257] 'process raft request' (duration: 282.70026ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:54.631853Z","caller":"traceutil/trace.go:171","msg":"trace[937263524] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1032; }","duration":"119.874703ms","start":"2026-04-22T00:23:54.511953Z","end":"2026-04-22T00:23:54.631827Z","steps":["trace[937263524] 'read index received' (duration: 37.987673ms)","trace[937263524] 'applied index is now lower than readState.Index' (duration: 81.88551ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:23:54.631987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.006705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:54.632023Z","caller":"traceutil/trace.go:171","msg":"trace[1827649222] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1001; }","duration":"120.091327ms","start":"2026-04-22T00:23:54.511923Z","end":"2026-04-22T00:23:54.632014Z","steps":["trace[1827649222] 'agreement among raft nodes before linearized reading' (duration: 120.005266ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:09.795038Z","caller":"traceutil/trace.go:171","msg":"trace[508425152] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"125.451839ms","start":"2026-04-22T00:24:09.669554Z","end":"2026-04-22T00:24:09.795006Z","steps":["trace[508425152] 'process raft request' (duration: 125.290906ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:09.973956Z","caller":"traceutil/trace.go:171","msg":"trace[338955783] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"105.692211ms","start":"2026-04-22T00:24:09.868236Z","end":"2026-04-22T00:24:09.973928Z","steps":["trace[338955783] 'process raft request' (duration: 100.001935ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:15.503922Z","caller":"traceutil/trace.go:171","msg":"trace[7192096] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"239.569965ms","start":"2026-04-22T00:24:15.26432Z","end":"2026-04-22T00:24:15.50389Z","steps":["trace[7192096] 'process raft request' (duration: 239.006725ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:16.455517Z","caller":"traceutil/trace.go:171","msg":"trace[666272979] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"110.755028ms","start":"2026-04-22T00:24:16.344701Z","end":"2026-04-22T00:24:16.455456Z","steps":["trace[666272979] 'process raft request' (duration: 101.428592ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:17.252552Z","caller":"traceutil/trace.go:171","msg":"trace[593920400] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"120.787656ms","start":"2026-04-22T00:24:17.131723Z","end":"2026-04-22T00:24:17.252511Z","steps":["trace[593920400] 'process raft request' (duration: 120.617903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:24:18.094383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.781302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T00:24:18.094468Z","caller":"traceutil/trace.go:171","msg":"trace[985719860] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1081; }","duration":"636.980775ms","start":"2026-04-22T00:24:17.457468Z","end":"2026-04-22T00:24:18.094448Z","steps":["trace[985719860] 'count revisions from in-memory index tree' (duration: 636.449146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:24:18.094522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.696004ms","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-22T00:24:18.094534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:24:17.457433Z","time spent":"637.089647ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-04-22T00:24:18.094575Z","caller":"traceutil/trace.go:171","msg":"trace[476407261] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1081; }","duration":"245.788036ms","start":"2026-04-22T00:24:17.848774Z","end":"2026-04-22T00:24:18.094562Z","steps":["trace[476407261] 'range keys from in-memory index tree' (duration: 245.541302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:24:18.094637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.627553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-22T00:24:18.094773Z","caller":"traceutil/trace.go:171","msg":"trace[1728391824] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1081; }","duration":"585.803496ms","start":"2026-04-22T00:24:17.508943Z","end":"2026-04-22T00:24:18.094746Z","steps":["trace[1728391824] 'range keys from in-memory index tree' (duration: 585.446349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:24:18.09483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:24:17.508919Z","time spent":"585.896148ms","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-22T00:24:42.400754Z","caller":"traceutil/trace.go:171","msg":"trace[70981574] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"162.617671ms","start":"2026-04-22T00:24:42.238107Z","end":"2026-04-22T00:24:42.400725Z","steps":["trace[70981574] 'process raft request' (duration: 162.410028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:24:42.639692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.485241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:24:42.64003Z","caller":"traceutil/trace.go:171","msg":"trace[533936593] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"234.86453ms","start":"2026-04-22T00:24:42.405137Z","end":"2026-04-22T00:24:42.640001Z","steps":["trace[533936593] 'process raft request' (duration: 77.007073ms)","trace[533936593] 'compare' (duration: 157.329749ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:24:42.640628Z","caller":"traceutil/trace.go:171","msg":"trace[348109654] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"233.777214ms","start":"2026-04-22T00:24:42.406837Z","end":"2026-04-22T00:24:42.640614Z","steps":["trace[348109654] 'process raft request' (duration: 233.572661ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:44.54876Z","caller":"traceutil/trace.go:171","msg":"trace[1597746723] linearizableReadLoop","detail":"{readStateIndex:1209; appliedIndex:1208; }","duration":"134.009517ms","start":"2026-04-22T00:24:44.414714Z","end":"2026-04-22T00:24:44.548723Z","steps":["trace[1597746723] 'read index received' (duration: 133.725092ms)","trace[1597746723] 'applied index is now lower than readState.Index' (duration: 282.894µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:24:44.548863Z","caller":"traceutil/trace.go:171","msg":"trace[254382802] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"139.577782ms","start":"2026-04-22T00:24:44.409248Z","end":"2026-04-22T00:24:44.548826Z","steps":["trace[254382802] 'process raft request' (duration: 139.241937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:24:44.549071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.330581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:24:44.549156Z","caller":"traceutil/trace.go:171","msg":"trace[32044543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1167; }","duration":"134.437693ms","start":"2026-04-22T00:24:44.414698Z","end":"2026-04-22T00:24:44.549136Z","steps":["trace[32044543] 'agreement among raft nodes before linearized reading' (duration: 134.23127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:24:44.563684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.148464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:24:44.563779Z","caller":"traceutil/trace.go:171","msg":"trace[888352475] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1168; }","duration":"115.293156ms","start":"2026-04-22T00:24:44.448462Z","end":"2026-04-22T00:24:44.563755Z","steps":["trace[888352475] 'agreement among raft nodes before linearized reading' (duration: 115.136464ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:44.563774Z","caller":"traceutil/trace.go:171","msg":"trace[1035058665] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"135.359527ms","start":"2026-04-22T00:24:44.428385Z","end":"2026-04-22T00:24:44.563745Z","steps":["trace[1035058665] 'process raft request' (duration: 135.002632ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:45.888726Z","caller":"traceutil/trace.go:171","msg":"trace[407021327] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"145.305362ms","start":"2026-04-22T00:24:45.74338Z","end":"2026-04-22T00:24:45.888686Z","steps":["trace[407021327] 'process raft request' (duration: 145.038387ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:46.766218Z","caller":"traceutil/trace.go:171","msg":"trace[565890785] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1217; }","duration":"102.917261ms","start":"2026-04-22T00:24:46.663282Z","end":"2026-04-22T00:24:46.7662Z","steps":["trace[565890785] 'read index received' (duration: 63.865421ms)","trace[565890785] 'applied index is now lower than readState.Index' (duration: 39.05116ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:24:46.76629Z","caller":"traceutil/trace.go:171","msg":"trace[1158343819] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"111.049136ms","start":"2026-04-22T00:24:46.655207Z","end":"2026-04-22T00:24:46.766256Z","steps":["trace[1158343819] 'process raft request' (duration: 71.945135ms)","trace[1158343819] 'compare' (duration: 38.946918ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:24:46.766518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.215935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-22T00:24:46.766613Z","caller":"traceutil/trace.go:171","msg":"trace[376410920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1175; }","duration":"103.369138ms","start":"2026-04-22T00:24:46.663225Z","end":"2026-04-22T00:24:46.766594Z","steps":["trace[376410920] 'agreement among raft nodes before linearized reading' (duration: 103.087523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:24:48.852943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.498162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5548"} {"level":"info","ts":"2026-04-22T00:24:48.853008Z","caller":"traceutil/trace.go:171","msg":"trace[530883690] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1181; }","duration":"152.606604ms","start":"2026-04-22T00:24:48.700388Z","end":"2026-04-22T00:24:48.852995Z","steps":["trace[530883690] 'range keys from in-memory index tree' (duration: 152.36114ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:48.907926Z","caller":"traceutil/trace.go:171","msg":"trace[1965468493] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"126.717469ms","start":"2026-04-22T00:24:48.781192Z","end":"2026-04-22T00:24:48.907909Z","steps":["trace[1965468493] 'read index received' (duration: 126.574407ms)","trace[1965468493] 'applied index is now lower than readState.Index' (duration: 142.572µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:24:48.908007Z","caller":"traceutil/trace.go:171","msg":"trace[792540467] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"135.731638ms","start":"2026-04-22T00:24:48.77227Z","end":"2026-04-22T00:24:48.908002Z","steps":["trace[792540467] 'process raft request' (duration: 135.537355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:24:48.908317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.647558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-22T00:24:48.908508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.296469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-22T00:24:48.908591Z","caller":"traceutil/trace.go:171","msg":"trace[1044511425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1182; }","duration":"127.41772ms","start":"2026-04-22T00:24:48.781152Z","end":"2026-04-22T00:24:48.908569Z","steps":["trace[1044511425] 'agreement among raft nodes before linearized reading' (duration: 127.069365ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:48.908454Z","caller":"traceutil/trace.go:171","msg":"trace[162163746] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1182; }","duration":"124.825981ms","start":"2026-04-22T00:24:48.783604Z","end":"2026-04-22T00:24:48.90843Z","steps":["trace[162163746] 'agreement among raft nodes before linearized reading' (duration: 124.526527ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:49.171802Z","caller":"traceutil/trace.go:171","msg":"trace[755093890] linearizableReadLoop","detail":"{readStateIndex:1227; appliedIndex:1226; }","duration":"224.62723ms","start":"2026-04-22T00:24:48.947149Z","end":"2026-04-22T00:24:49.171777Z","steps":["trace[755093890] 'read index received' (duration: 2.093171ms)","trace[755093890] 'applied index is now lower than readState.Index' (duration: 222.532939ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:24:49.17192Z","caller":"traceutil/trace.go:171","msg":"trace[318666037] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"258.501986ms","start":"2026-04-22T00:24:48.913407Z","end":"2026-04-22T00:24:49.171909Z","steps":["trace[318666037] 'process raft request' (duration: 258.211742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:24:49.172216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.043877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T00:24:49.172289Z","caller":"traceutil/trace.go:171","msg":"trace[714008756] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1184; }","duration":"225.168469ms","start":"2026-04-22T00:24:48.947104Z","end":"2026-04-22T00:24:49.172272Z","steps":["trace[714008756] 'agreement among raft nodes before linearized reading' (duration: 225.056717ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:25:24.431333Z","caller":"traceutil/trace.go:171","msg":"trace[1246860492] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"244.773521ms","start":"2026-04-22T00:25:24.18653Z","end":"2026-04-22T00:25:24.431303Z","steps":["trace[1246860492] 'process raft request' (duration: 244.200043ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:25:31.597113Z","caller":"traceutil/trace.go:171","msg":"trace[1564790980] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1413; }","duration":"127.095124ms","start":"2026-04-22T00:25:31.469986Z","end":"2026-04-22T00:25:31.597081Z","steps":["trace[1564790980] 'read index received' (duration: 61.548466ms)","trace[1564790980] 'applied index is now lower than readState.Index' (duration: 65.544978ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:25:31.597303Z","caller":"traceutil/trace.go:171","msg":"trace[1153825916] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"294.832996ms","start":"2026-04-22T00:25:31.302438Z","end":"2026-04-22T00:25:31.597271Z","steps":["trace[1153825916] 'process raft request' (duration: 229.141846ms)","trace[1153825916] 'compare' (duration: 65.168523ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:25:31.597355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.337048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-22T00:25:31.597391Z","caller":"traceutil/trace.go:171","msg":"trace[378313702] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1360; }","duration":"127.399718ms","start":"2026-04-22T00:25:31.469979Z","end":"2026-04-22T00:25:31.597378Z","steps":["trace[378313702] 'agreement among raft nodes before linearized reading' (duration: 127.212076ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:25:31.812823Z","caller":"traceutil/trace.go:171","msg":"trace[1910751827] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"209.306243ms","start":"2026-04-22T00:25:31.603464Z","end":"2026-04-22T00:25:31.81277Z","steps":["trace[1910751827] 'process raft request' (duration: 209.05739ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:25:32.967946Z","caller":"traceutil/trace.go:171","msg":"trace[1561585338] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"487.103157ms","start":"2026-04-22T00:25:32.48081Z","end":"2026-04-22T00:25:32.967913Z","steps":["trace[1561585338] 'process raft request' (duration: 486.897625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:25:32.968169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:25:32.480769Z","time spent":"487.2685ms","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:25:52.204758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.400728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-22T00:25:52.204827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.668433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:1117"} {"level":"info","ts":"2026-04-22T00:25:52.204882Z","caller":"traceutil/trace.go:171","msg":"trace[494619440] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1432; }","duration":"117.580861ms","start":"2026-04-22T00:25:52.087275Z","end":"2026-04-22T00:25:52.204856Z","steps":["trace[494619440] 'range keys from in-memory index tree' (duration: 117.147084ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:25:52.204927Z","caller":"traceutil/trace.go:171","msg":"trace[567144047] range","detail":"{range_begin:/registry/services/endpoints/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:1432; }","duration":"125.793174ms","start":"2026-04-22T00:25:52.079089Z","end":"2026-04-22T00:25:52.204882Z","steps":["trace[567144047] 'range keys from in-memory index tree' (duration: 125.388438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:25:53.045522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.993045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-6cvfb\" ","response":"range_response_count:1 size:5481"} {"level":"info","ts":"2026-04-22T00:25:53.045605Z","caller":"traceutil/trace.go:171","msg":"trace[1209891508] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-6cvfb; range_end:; response_count:1; response_revision:1433; }","duration":"672.112548ms","start":"2026-04-22T00:25:52.373472Z","end":"2026-04-22T00:25:53.045585Z","steps":["trace[1209891508] 'range keys from in-memory index tree' (duration: 671.852204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:25:53.04566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:25:52.373456Z","time spent":"672.195388ms","remote":"127.0.0.1:42884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5503,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-6cvfb\" "} {"level":"warn","ts":"2026-04-22T00:25:53.046131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.907239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T00:25:53.046132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.739855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:25:53.046175Z","caller":"traceutil/trace.go:171","msg":"trace[720553539] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1433; }","duration":"669.96693ms","start":"2026-04-22T00:25:52.376196Z","end":"2026-04-22T00:25:53.046162Z","steps":["trace[720553539] 'count revisions from in-memory index tree' (duration: 669.866719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:25:53.046204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.668138ms","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":"warn","ts":"2026-04-22T00:25:53.046212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:25:52.376191Z","time spent":"670.01215ms","remote":"127.0.0.1:43020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-04-22T00:25:53.046241Z","caller":"traceutil/trace.go:171","msg":"trace[1727364525] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/; range_end:/registry/persistentvolumeclaims/default0; response_count:1; response_revision:1433; }","duration":"535.754819ms","start":"2026-04-22T00:25:52.510475Z","end":"2026-04-22T00:25:53.04623Z","steps":["trace[1727364525] 'range keys from in-memory index tree' (duration: 535.493956ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:25:53.046225Z","caller":"traceutil/trace.go:171","msg":"trace[662246394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1433; }","duration":"631.876747ms","start":"2026-04-22T00:25:52.414322Z","end":"2026-04-22T00:25:53.046199Z","steps":["trace[662246394] 'range keys from in-memory index tree' (duration: 631.683975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:25:53.046269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:25:52.510452Z","time spent":"535.81063ms","remote":"127.0.0.1:42852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":1487,"request content":"key:\"/registry/persistentvolumeclaims/default/\" range_end:\"/registry/persistentvolumeclaims/default0\" limit:500 "} {"level":"warn","ts":"2026-04-22T00:25:53.046283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:25:52.414313Z","time spent":"631.957938ms","remote":"127.0.0.1:42654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T00:25:53.290502Z","caller":"traceutil/trace.go:171","msg":"trace[568328224] linearizableReadLoop","detail":"{readStateIndex:1492; appliedIndex:1491; }","duration":"224.844575ms","start":"2026-04-22T00:25:53.065639Z","end":"2026-04-22T00:25:53.290484Z","steps":["trace[568328224] 'read index received' (duration: 224.745494ms)","trace[568328224] 'applied index is now lower than readState.Index' (duration: 98.211µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:25:53.290606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.951006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/envoy-gateway-system/\" range_end:\"/registry/persistentvolumeclaims/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:25:53.290634Z","caller":"traceutil/trace.go:171","msg":"trace[1014948030] range","detail":"{range_begin:/registry/persistentvolumeclaims/envoy-gateway-system/; range_end:/registry/persistentvolumeclaims/envoy-gateway-system0; response_count:0; response_revision:1434; }","duration":"225.016277ms","start":"2026-04-22T00:25:53.065607Z","end":"2026-04-22T00:25:53.290624Z","steps":["trace[1014948030] 'agreement among raft nodes before linearized reading' (duration: 224.959676ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:25:53.290615Z","caller":"traceutil/trace.go:171","msg":"trace[882783677] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"227.712061ms","start":"2026-04-22T00:25:53.062836Z","end":"2026-04-22T00:25:53.290548Z","steps":["trace[882783677] 'process raft request' (duration: 227.548409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:25:53.378391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.595953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/default/\" range_end:\"/registry/rolebindings/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T00:25:53.378442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.729257ms","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-22T00:25:53.378483Z","caller":"traceutil/trace.go:171","msg":"trace[651385836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1435; }","duration":"165.799957ms","start":"2026-04-22T00:25:53.212672Z","end":"2026-04-22T00:25:53.378472Z","steps":["trace[651385836] 'agreement among raft nodes before linearized reading' (duration: 165.668895ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:25:53.378481Z","caller":"traceutil/trace.go:171","msg":"trace[1595575106] range","detail":"{range_begin:/registry/rolebindings/default/; range_end:/registry/rolebindings/default0; response_count:0; response_revision:1435; }","duration":"247.724584ms","start":"2026-04-22T00:25:53.130736Z","end":"2026-04-22T00:25:53.37846Z","steps":["trace[1595575106] 'agreement among raft nodes before linearized reading' (duration: 247.581382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:25:53.378639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.980427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-6cvfb\" ","response":"range_response_count:1 size:5481"} {"level":"warn","ts":"2026-04-22T00:25:53.378641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.212839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T00:25:53.378696Z","caller":"traceutil/trace.go:171","msg":"trace[73136123] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-6cvfb; range_end:; response_count:1; response_revision:1435; }","duration":"248.066069ms","start":"2026-04-22T00:25:53.130613Z","end":"2026-04-22T00:25:53.378679Z","steps":["trace[73136123] 'agreement among raft nodes before linearized reading' (duration: 247.910827ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:25:53.378705Z","caller":"traceutil/trace.go:171","msg":"trace[153088698] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1435; }","duration":"293.328291ms","start":"2026-04-22T00:25:53.08536Z","end":"2026-04-22T00:25:53.378689Z","steps":["trace[153088698] 'agreement among raft nodes before linearized reading' (duration: 293.213139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:25:53.378757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.9979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-04-22T00:25:53.378872Z","caller":"traceutil/trace.go:171","msg":"trace[505314933] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1435; }","duration":"245.164352ms","start":"2026-04-22T00:25:53.13368Z","end":"2026-04-22T00:25:53.378845Z","steps":["trace[505314933] 'agreement among raft nodes before linearized reading' (duration: 244.785277ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:26:01.606607Z","caller":"traceutil/trace.go:171","msg":"trace[1285624657] linearizableReadLoop","detail":"{readStateIndex:1521; appliedIndex:1520; }","duration":"240.879916ms","start":"2026-04-22T00:26:01.365698Z","end":"2026-04-22T00:26:01.606578Z","steps":["trace[1285624657] 'read index received' (duration: 240.720824ms)","trace[1285624657] 'applied index is now lower than readState.Index' (duration: 158.072µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:26:01.606673Z","caller":"traceutil/trace.go:171","msg":"trace[1307711479] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"242.557187ms","start":"2026-04-22T00:26:01.364082Z","end":"2026-04-22T00:26:01.606639Z","steps":["trace[1307711479] 'process raft request' (duration: 242.327964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:26:01.606899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.16583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/local-path-storage/\" range_end:\"/registry/events/local-path-storage0\" limit:500 ","response":"range_response_count:11 size:9384"} {"level":"info","ts":"2026-04-22T00:26:01.606957Z","caller":"traceutil/trace.go:171","msg":"trace[583238706] range","detail":"{range_begin:/registry/events/local-path-storage/; range_end:/registry/events/local-path-storage0; response_count:11; response_revision:1461; }","duration":"241.259271ms","start":"2026-04-22T00:26:01.365682Z","end":"2026-04-22T00:26:01.606941Z","steps":["trace[583238706] 'agreement among raft nodes before linearized reading' (duration: 240.998737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:26:01.657315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.075553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-22T00:26:01.657406Z","caller":"traceutil/trace.go:171","msg":"trace[27855907] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:1462; }","duration":"243.221025ms","start":"2026-04-22T00:26:01.414164Z","end":"2026-04-22T00:26:01.657385Z","steps":["trace[27855907] 'agreement among raft nodes before linearized reading' (duration: 242.994652ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:26:01.657639Z","caller":"traceutil/trace.go:171","msg":"trace[983568300] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"234.042011ms","start":"2026-04-22T00:26:01.423556Z","end":"2026-04-22T00:26:01.657598Z","steps":["trace[983568300] 'process raft request' (duration: 233.471464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:26:01.657689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.693427ms","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-22T00:26:01.657826Z","caller":"traceutil/trace.go:171","msg":"trace[1106479960] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1462; }","duration":"201.87576ms","start":"2026-04-22T00:26:01.455939Z","end":"2026-04-22T00:26:01.657815Z","steps":["trace[1106479960] 'agreement among raft nodes before linearized reading' (duration: 201.695287ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:26:01.818994Z","caller":"traceutil/trace.go:171","msg":"trace[10616306] linearizableReadLoop","detail":"{readStateIndex:1523; appliedIndex:1522; }","duration":"153.768609ms","start":"2026-04-22T00:26:01.665197Z","end":"2026-04-22T00:26:01.818965Z","steps":["trace[10616306] 'read index received' (duration: 97.555497ms)","trace[10616306] 'applied index is now lower than readState.Index' (duration: 56.211752ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:26:01.819043Z","caller":"traceutil/trace.go:171","msg":"trace[360470239] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"157.453145ms","start":"2026-04-22T00:26:01.661528Z","end":"2026-04-22T00:26:01.818981Z","steps":["trace[360470239] 'process raft request' (duration: 101.281293ms)","trace[360470239] 'compare' (duration: 56.03184ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:26:01.819225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.547977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-0del97\" ","response":"range_response_count:1 size:692"} {"level":"info","ts":"2026-04-22T00:26:01.819271Z","caller":"traceutil/trace.go:171","msg":"trace[1191160589] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-0del97; range_end:; response_count:1; response_revision:1463; }","duration":"125.656338ms","start":"2026-04-22T00:26:01.693602Z","end":"2026-04-22T00:26:01.819258Z","steps":["trace[1191160589] 'agreement among raft nodes before linearized reading' (duration: 125.537527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:26:01.819267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.041512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" limit:500 ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-04-22T00:26:01.819341Z","caller":"traceutil/trace.go:171","msg":"trace[914868225] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1463; }","duration":"154.138933ms","start":"2026-04-22T00:26:01.665184Z","end":"2026-04-22T00:26:01.819323Z","steps":["trace[914868225] 'agreement among raft nodes before linearized reading' (duration: 153.917841ms)"],"step_count":1}