{"level":"warn","ts":"2026-04-26T00:41:01.488524Z","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-26T00:41:01.488609Z","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-26T00:41:01.488666Z","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-26T00:41:01.488675Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-04-26T00:41:01.488695Z","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-26T00:41:01.489319Z","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-26T00:41:01.489399Z","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-26T00:41:01.513029Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"23.378097ms"} {"level":"info","ts":"2026-04-26T00:41:01.709601Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-04-26T00:41:01.709726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-04-26T00:41:01.70979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-04-26T00:41:01.709811Z","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-26T00:41:01.709835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-04-26T00:41:01.709886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-04-26T00:41:01.739005Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-26T00:41:01.743605Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-26T00:41:01.750565Z","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-26T00:41:01.762755Z","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-26T00:41:01.762944Z","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-26T00:41:01.763428Z","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-26T00:41:01.763676Z","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-26T00:41:01.763691Z","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-26T00:41:01.765821Z","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-26T00:41:01.766001Z","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-26T00:41:01.766029Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-26T00:41:01.766678Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-04-26T00:41:01.766718Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-04-26T00:41:01.767325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-04-26T00:41:01.767417Z","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-26T00:41:02.510737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-04-26T00:41:02.510819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-04-26T00:41:02.510847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-04-26T00:41:02.510866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-04-26T00:41:02.510876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-04-26T00:41:02.510897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-04-26T00:41:02.510913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-04-26T00:41:02.54445Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-26T00:41:02.554486Z","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-26T00:41:02.554503Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-26T00:41:02.554577Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-26T00:41:02.554837Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-26T00:41:02.554865Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-26T00:41:02.557157Z","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-26T00:41:02.557308Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-26T00:41:02.557345Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-26T00:41:02.557991Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-04-26T00:41:02.558252Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-26T00:41:04.396876Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291073999179388,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-26T00:41:05.061506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.85223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-26T00:41:05.061668Z","caller":"traceutil/trace.go:171","msg":"trace[429145038] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.166361876s","start":"2026-04-26T00:41:03.895291Z","end":"2026-04-26T00:41:05.061653Z","steps":["trace[429145038] 'process raft request' (duration: 1.166306634s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:05.061738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:03.895282Z","time spent":"1.166429938s","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T00:41:05.061916Z","caller":"traceutil/trace.go:171","msg":"trace[1054235223] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.166667258s","start":"2026-04-26T00:41:03.895194Z","end":"2026-04-26T00:41:05.061861Z","steps":["trace[1054235223] 'process raft request' (duration: 731.886933ms)","trace[1054235223] 'compare' (duration: 433.739056ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:05.062043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:03.895174Z","time spent":"1.166810602s","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T00:41:05.080937Z","caller":"traceutil/trace.go:171","msg":"trace[232266540] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.185704065s","start":"2026-04-26T00:41:03.895215Z","end":"2026-04-26T00:41:05.080919Z","steps":["trace[232266540] 'process raft request' (duration: 1.185641302s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:05.081046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:03.895196Z","time spent":"1.185815438s","remote":"127.0.0.1:34076","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-26T00:41:05.081154Z","caller":"traceutil/trace.go:171","msg":"trace[58208635] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.185031057s","start":"2026-04-26T00:41:03.896096Z","end":"2026-04-26T00:41:05.081127Z","steps":["trace[58208635] 'process raft request' (duration: 1.184781998s)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.08096Z","caller":"traceutil/trace.go:171","msg":"trace[1680652914] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.185620811s","start":"2026-04-26T00:41:03.895312Z","end":"2026-04-26T00:41:05.080933Z","steps":["trace[1680652914] 'process raft request' (duration: 1.185463585s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:05.081277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:03.896084Z","time spent":"1.185144962s","remote":"127.0.0.1:33994","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-26T00:41:05.081399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:03.895296Z","time spent":"1.186044817s","remote":"127.0.0.1:34076","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-26T00:41:05.081953Z","caller":"traceutil/trace.go:171","msg":"trace[2007194728] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.185360211s","start":"2026-04-26T00:41:03.896553Z","end":"2026-04-26T00:41:05.081913Z","steps":["trace[2007194728] 'process raft request' (duration: 1.184347852s)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.081951Z","caller":"traceutil/trace.go:171","msg":"trace[1637684600] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:6; }","duration":"1.185692703s","start":"2026-04-26T00:41:03.896231Z","end":"2026-04-26T00:41:05.081924Z","steps":["trace[1637684600] 'read index received' (duration: 730.860443ms)","trace[1637684600] 'applied index is now lower than readState.Index' (duration: 454.831ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:05.082123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18588367s","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-26T00:41:05.082179Z","caller":"traceutil/trace.go:171","msg":"trace[1836601643] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"1.185980524s","start":"2026-04-26T00:41:03.896187Z","end":"2026-04-26T00:41:05.082167Z","steps":["trace[1836601643] 'agreement among raft nodes before linearized reading' (duration: 1.18586776s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:05.082193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185948022s","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-26T00:41:05.082265Z","caller":"traceutil/trace.go:171","msg":"trace[2020237855] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.174792091s","start":"2026-04-26T00:41:03.907464Z","end":"2026-04-26T00:41:05.082256Z","steps":["trace[2020237855] 'process raft request' (duration: 1.174383236s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:05.08225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186012555s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-04-26T00:41:05.082327Z","caller":"traceutil/trace.go:171","msg":"trace[575969227] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"1.186113078s","start":"2026-04-26T00:41:03.896201Z","end":"2026-04-26T00:41:05.082314Z","steps":["trace[575969227] 'agreement among raft nodes before linearized reading' (duration: 1.185961233s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:05.082214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:03.896165Z","time spent":"1.186041476s","remote":"127.0.0.1:33612","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-26T00:41:05.082392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:03.89619Z","time spent":"1.186164791s","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-26T00:41:05.08226Z","caller":"traceutil/trace.go:171","msg":"trace[1758715542] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:0; response_revision:10; }","duration":"1.186037726s","start":"2026-04-26T00:41:03.896205Z","end":"2026-04-26T00:41:05.082243Z","steps":["trace[1758715542] 'agreement among raft nodes before linearized reading' (duration: 1.185939732s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:05.082496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:03.896192Z","time spent":"1.186285495s","remote":"127.0.0.1:33550","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":"warn","ts":"2026-04-26T00:41:05.082335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:03.907451Z","time spent":"1.174845824s","remote":"127.0.0.1:33686","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-26T00:41:05.082063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:03.89654Z","time spent":"1.185453784s","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T00:41:05.250211Z","caller":"traceutil/trace.go:171","msg":"trace[1061450968] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:13; }","duration":"168.181827ms","start":"2026-04-26T00:41:05.082005Z","end":"2026-04-26T00:41:05.250187Z","steps":["trace[1061450968] 'read index received' (duration: 127.521905ms)","trace[1061450968] 'applied index is now lower than readState.Index' (duration: 40.658322ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:05.250332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.630959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T00:41:05.250426Z","caller":"traceutil/trace.go:171","msg":"trace[111319838] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:12; }","duration":"452.742453ms","start":"2026-04-26T00:41:04.797664Z","end":"2026-04-26T00:41:05.250406Z","steps":["trace[111319838] 'agreement among raft nodes before linearized reading' (duration: 452.532666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:05.250441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304763474s","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-26T00:41:05.250492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:04.797629Z","time spent":"452.843477ms","remote":"127.0.0.1:33880","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-26T00:41:05.250479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31500864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T00:41:05.250526Z","caller":"traceutil/trace.go:171","msg":"trace[1371568692] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"1.304872498s","start":"2026-04-26T00:41:03.945641Z","end":"2026-04-26T00:41:05.250514Z","steps":["trace[1371568692] 'agreement among raft nodes before linearized reading' (duration: 1.304763314s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:05.250593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:03.945623Z","time spent":"1.304960202s","remote":"127.0.0.1:33650","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-26T00:41:05.250498Z","caller":"traceutil/trace.go:171","msg":"trace[1217558930] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"185.116325ms","start":"2026-04-26T00:41:05.065143Z","end":"2026-04-26T00:41:05.25026Z","steps":["trace[1217558930] 'process raft request' (duration: 144.333808ms)","trace[1217558930] 'compare' (duration: 40.526667ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:05.250619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166743534s","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-26T00:41:05.250644Z","caller":"traceutil/trace.go:171","msg":"trace[1511365848] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"1.315139064s","start":"2026-04-26T00:41:03.935438Z","end":"2026-04-26T00:41:05.250577Z","steps":["trace[1511365848] 'agreement among raft nodes before linearized reading' (duration: 1.315020259s)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.250714Z","caller":"traceutil/trace.go:171","msg":"trace[334867412] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:12; }","duration":"1.16691695s","start":"2026-04-26T00:41:04.083777Z","end":"2026-04-26T00:41:05.250694Z","steps":["trace[334867412] 'agreement among raft nodes before linearized reading' (duration: 1.166724033s)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.250764Z","caller":"traceutil/trace.go:171","msg":"trace[781751732] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"185.565212ms","start":"2026-04-26T00:41:05.065185Z","end":"2026-04-26T00:41:05.25075Z","steps":["trace[781751732] 'process raft request' (duration: 184.952879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:05.250731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:03.935424Z","time spent":"1.31529197s","remote":"127.0.0.1:33650","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-26T00:41:05.250767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:04.083755Z","time spent":"1.167002103s","remote":"127.0.0.1:33780","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-26T00:41:05.250433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.956846ms","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-26T00:41:05.250876Z","caller":"traceutil/trace.go:171","msg":"trace[543856423] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:12; }","duration":"456.495786ms","start":"2026-04-26T00:41:04.79437Z","end":"2026-04-26T00:41:05.250866Z","steps":["trace[543856423] 'agreement among raft nodes before linearized reading' (duration: 455.927565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:05.250925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:04.794347Z","time spent":"456.566399ms","remote":"127.0.0.1:33898","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-26T00:41:05.251002Z","caller":"traceutil/trace.go:171","msg":"trace[1136554885] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"166.006145ms","start":"2026-04-26T00:41:05.084984Z","end":"2026-04-26T00:41:05.250991Z","steps":["trace[1136554885] 'process raft request' (duration: 165.59814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:05.251037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.096758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T00:41:05.25107Z","caller":"traceutil/trace.go:171","msg":"trace[891269167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"166.13061ms","start":"2026-04-26T00:41:05.08493Z","end":"2026-04-26T00:41:05.25106Z","steps":["trace[891269167] 'agreement among raft nodes before linearized reading' (duration: 166.048516ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.251125Z","caller":"traceutil/trace.go:171","msg":"trace[248443769] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"165.999074ms","start":"2026-04-26T00:41:05.085114Z","end":"2026-04-26T00:41:05.251113Z","steps":["trace[248443769] 'process raft request' (duration: 165.543368ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.251209Z","caller":"traceutil/trace.go:171","msg":"trace[808125194] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"165.985025ms","start":"2026-04-26T00:41:05.085214Z","end":"2026-04-26T00:41:05.251199Z","steps":["trace[808125194] 'process raft request' (duration: 165.473995ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.251221Z","caller":"traceutil/trace.go:171","msg":"trace[1163114693] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"165.113751ms","start":"2026-04-26T00:41:05.086099Z","end":"2026-04-26T00:41:05.251213Z","steps":["trace[1163114693] 'process raft request' (duration: 164.718276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:05.251216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315777299s","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-26T00:41:05.251254Z","caller":"traceutil/trace.go:171","msg":"trace[1441066236] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"1.315838101s","start":"2026-04-26T00:41:03.935406Z","end":"2026-04-26T00:41:05.251244Z","steps":["trace[1441066236] 'agreement among raft nodes before linearized reading' (duration: 1.315778899s)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.251289Z","caller":"traceutil/trace.go:171","msg":"trace[71485464] transaction","detail":"{read_only:false; number_of_response:0; response_revision:15; }","duration":"166.016566ms","start":"2026-04-26T00:41:05.085266Z","end":"2026-04-26T00:41:05.251283Z","steps":["trace[71485464] 'process raft request' (duration: 165.453424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:05.251293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:03.93539Z","time spent":"1.315896683s","remote":"127.0.0.1:33650","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":"info","ts":"2026-04-26T00:41:05.251407Z","caller":"traceutil/trace.go:171","msg":"trace[466286957] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"157.676961ms","start":"2026-04-26T00:41:05.0937Z","end":"2026-04-26T00:41:05.251377Z","steps":["trace[466286957] 'process raft request' (duration: 157.204043ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.251526Z","caller":"traceutil/trace.go:171","msg":"trace[953807145] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"165.440963ms","start":"2026-04-26T00:41:05.086077Z","end":"2026-04-26T00:41:05.251518Z","steps":["trace[953807145] 'process raft request' (duration: 164.712646ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.251423Z","caller":"traceutil/trace.go:171","msg":"trace[1067852981] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"165.359321ms","start":"2026-04-26T00:41:05.086056Z","end":"2026-04-26T00:41:05.251415Z","steps":["trace[1067852981] 'process raft request' (duration: 164.684865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:05.605557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.886412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-26T00:41:05.606104Z","caller":"traceutil/trace.go:171","msg":"trace[2075221675] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"288.925657ms","start":"2026-04-26T00:41:05.317149Z","end":"2026-04-26T00:41:05.606075Z","steps":["trace[2075221675] 'process raft request' (duration: 75.466163ms)","trace[2075221675] 'compare' (duration: 212.752538ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:41:05.606238Z","caller":"traceutil/trace.go:171","msg":"trace[1361869480] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"287.437571ms","start":"2026-04-26T00:41:05.318757Z","end":"2026-04-26T00:41:05.606195Z","steps":["trace[1361869480] 'process raft request' (duration: 287.289456ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.606303Z","caller":"traceutil/trace.go:171","msg":"trace[67168871] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"288.335916ms","start":"2026-04-26T00:41:05.317954Z","end":"2026-04-26T00:41:05.60629Z","steps":["trace[67168871] 'process raft request' (duration: 288.031405ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.606378Z","caller":"traceutil/trace.go:171","msg":"trace[557355565] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"287.476863ms","start":"2026-04-26T00:41:05.318896Z","end":"2026-04-26T00:41:05.606373Z","steps":["trace[557355565] 'process raft request' (duration: 287.203923ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.606548Z","caller":"traceutil/trace.go:171","msg":"trace[1492099008] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"287.522494ms","start":"2026-04-26T00:41:05.31902Z","end":"2026-04-26T00:41:05.606542Z","steps":["trace[1492099008] 'process raft request' (duration: 287.13012ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.60683Z","caller":"traceutil/trace.go:171","msg":"trace[1466377788] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"268.914774ms","start":"2026-04-26T00:41:05.3379Z","end":"2026-04-26T00:41:05.606814Z","steps":["trace[1466377788] 'process raft request' (duration: 268.873463ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.607022Z","caller":"traceutil/trace.go:171","msg":"trace[2043976966] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"287.826227ms","start":"2026-04-26T00:41:05.31914Z","end":"2026-04-26T00:41:05.606966Z","steps":["trace[2043976966] 'process raft request' (duration: 287.417851ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.607149Z","caller":"traceutil/trace.go:171","msg":"trace[1898384306] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"288.081706ms","start":"2026-04-26T00:41:05.318978Z","end":"2026-04-26T00:41:05.60706Z","steps":["trace[1898384306] 'process raft request' (duration: 287.746213ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.60732Z","caller":"traceutil/trace.go:171","msg":"trace[376493468] linearizableReadLoop","detail":"{readStateIndex:42; appliedIndex:35; }","duration":"271.879085ms","start":"2026-04-26T00:41:05.335418Z","end":"2026-04-26T00:41:05.607297Z","steps":["trace[376493468] 'read index received' (duration: 57.172304ms)","trace[376493468] 'applied index is now lower than readState.Index' (duration: 214.704161ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:05.607505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.944413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-04-26T00:41:05.60756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.919222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T00:41:05.607664Z","caller":"traceutil/trace.go:171","msg":"trace[695226602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:39; }","duration":"287.081128ms","start":"2026-04-26T00:41:05.320517Z","end":"2026-04-26T00:41:05.607599Z","steps":["trace[695226602] 'agreement among raft nodes before linearized reading' (duration: 286.809738ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.6077Z","caller":"traceutil/trace.go:171","msg":"trace[858114280] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:39; }","duration":"270.099288ms","start":"2026-04-26T00:41:05.337584Z","end":"2026-04-26T00:41:05.607683Z","steps":["trace[858114280] 'agreement among raft nodes before linearized reading' (duration: 269.917312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:05.607917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.464985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-26T00:41:05.608137Z","caller":"traceutil/trace.go:171","msg":"trace[1152727866] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:39; }","duration":"290.710554ms","start":"2026-04-26T00:41:05.31741Z","end":"2026-04-26T00:41:05.60812Z","steps":["trace[1152727866] 'agreement among raft nodes before linearized reading' (duration: 290.015329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:05.881824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.874558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-26T00:41:05.881941Z","caller":"traceutil/trace.go:171","msg":"trace[54052410] linearizableReadLoop","detail":"{readStateIndex:50; appliedIndex:49; }","duration":"200.539106ms","start":"2026-04-26T00:41:05.681386Z","end":"2026-04-26T00:41:05.881925Z","steps":["trace[54052410] 'read index received' (duration: 46.501232ms)","trace[54052410] 'applied index is now lower than readState.Index' (duration: 154.036413ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:41:05.882091Z","caller":"traceutil/trace.go:171","msg":"trace[1360176347] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"201.495753ms","start":"2026-04-26T00:41:05.680485Z","end":"2026-04-26T00:41:05.881981Z","steps":["trace[1360176347] 'process raft request' (duration: 47.374786ms)","trace[1360176347] 'compare' (duration: 153.748313ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:05.88216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.765644ms","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-26T00:41:05.882191Z","caller":"traceutil/trace.go:171","msg":"trace[1242747886] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:0; response_revision:46; }","duration":"200.801406ms","start":"2026-04-26T00:41:05.68138Z","end":"2026-04-26T00:41:05.882182Z","steps":["trace[1242747886] 'agreement among raft nodes before linearized reading' (duration: 200.588398ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.882396Z","caller":"traceutil/trace.go:171","msg":"trace[272994985] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"198.304531ms","start":"2026-04-26T00:41:05.684067Z","end":"2026-04-26T00:41:05.882372Z","steps":["trace[272994985] 'process raft request' (duration: 198.239969ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:05.882461Z","caller":"traceutil/trace.go:171","msg":"trace[514243288] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"200.511065ms","start":"2026-04-26T00:41:05.68194Z","end":"2026-04-26T00:41:05.882451Z","steps":["trace[514243288] 'process raft request' (duration: 200.242115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:06.641703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.144011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-26T00:41:06.641841Z","caller":"traceutil/trace.go:171","msg":"trace[1017482397] transaction","detail":"{read_only:false; response_revision:93; number_of_response:1; }","duration":"275.066681ms","start":"2026-04-26T00:41:06.366756Z","end":"2026-04-26T00:41:06.641823Z","steps":["trace[1017482397] 'process raft request' (duration: 23.733603ms)","trace[1017482397] 'compare' (duration: 250.971784ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:08.956905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.541358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-26T00:41:08.957085Z","caller":"traceutil/trace.go:171","msg":"trace[780345625] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:251; }","duration":"161.916875ms","start":"2026-04-26T00:41:08.795152Z","end":"2026-04-26T00:41:08.957069Z","steps":["trace[780345625] 'read index received' (duration: 21.084036ms)","trace[780345625] 'applied index is now lower than readState.Index' (duration: 140.831899ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:41:08.957223Z","caller":"traceutil/trace.go:171","msg":"trace[1339524998] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"189.335493ms","start":"2026-04-26T00:41:08.767842Z","end":"2026-04-26T00:41:08.957178Z","steps":["trace[1339524998] 'process raft request' (duration: 48.438492ms)","trace[1339524998] 'compare' (duration: 140.429474ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:08.957397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.229298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:08.957374Z","caller":"traceutil/trace.go:171","msg":"trace[77494947] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"189.380936ms","start":"2026-04-26T00:41:08.767956Z","end":"2026-04-26T00:41:08.957337Z","steps":["trace[77494947] 'process raft request' (duration: 189.051333ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:08.957445Z","caller":"traceutil/trace.go:171","msg":"trace[359736704] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:0; response_revision:247; }","duration":"162.312651ms","start":"2026-04-26T00:41:08.79512Z","end":"2026-04-26T00:41:08.957432Z","steps":["trace[359736704] 'agreement among raft nodes before linearized reading' (duration: 162.003259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:09.911683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.861913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-26T00:41:09.911787Z","caller":"traceutil/trace.go:171","msg":"trace[1812851819] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"951.332544ms","start":"2026-04-26T00:41:08.960437Z","end":"2026-04-26T00:41:09.911769Z","steps":["trace[1812851819] 'process raft request' (duration: 585.337316ms)","trace[1812851819] 'compare' (duration: 365.714928ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:09.911839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:08.960422Z","time spent":"951.398495ms","remote":"127.0.0.1:33700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T00:41:09.973263Z","caller":"traceutil/trace.go:171","msg":"trace[1894180767] linearizableReadLoop","detail":"{readStateIndex:257; appliedIndex:253; }","duration":"551.876376ms","start":"2026-04-26T00:41:09.421365Z","end":"2026-04-26T00:41:09.973242Z","steps":["trace[1894180767] 'read index received' (duration: 124.426891ms)","trace[1894180767] 'applied index is now lower than readState.Index' (duration: 427.448485ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:41:09.973382Z","caller":"traceutil/trace.go:171","msg":"trace[2050254227] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"1.011246665s","start":"2026-04-26T00:41:08.962106Z","end":"2026-04-26T00:41:09.973353Z","steps":["trace[2050254227] 'process raft request' (duration: 1.011050588s)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:09.973469Z","caller":"traceutil/trace.go:171","msg":"trace[786727701] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"1.010108984s","start":"2026-04-26T00:41:08.96335Z","end":"2026-04-26T00:41:09.973459Z","steps":["trace[786727701] 'process raft request' (duration: 1.009855955s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:09.973482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.255216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-26T00:41:09.973465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.082933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:09.973519Z","caller":"traceutil/trace.go:171","msg":"trace[1706583164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:251; }","duration":"300.327408ms","start":"2026-04-26T00:41:09.67318Z","end":"2026-04-26T00:41:09.973508Z","steps":["trace[1706583164] 'agreement among raft nodes before linearized reading' (duration: 300.259196ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:09.973536Z","caller":"traceutil/trace.go:171","msg":"trace[2015231897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:251; }","duration":"552.257619ms","start":"2026-04-26T00:41:09.421261Z","end":"2026-04-26T00:41:09.973519Z","steps":["trace[2015231897] 'agreement among raft nodes before linearized reading' (duration: 552.105433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:09.973556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:09.673163Z","time spent":"300.38687ms","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-26T00:41:09.973559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:08.963338Z","time spent":"1.010171755s","remote":"127.0.0.1:33712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T00:41:09.973576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:09.421242Z","time spent":"552.324351ms","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-26T00:41:09.973684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:08.962091Z","time spent":"1.011349899s","remote":"127.0.0.1:33596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T00:41:09.973276Z","caller":"traceutil/trace.go:171","msg":"trace[297551455] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"1.012712388s","start":"2026-04-26T00:41:08.960543Z","end":"2026-04-26T00:41:09.973256Z","steps":["trace[297551455] 'process raft request' (duration: 1.012508841s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:09.973814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:08.960522Z","time spent":"1.013249748s","remote":"127.0.0.1:33858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":356,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T00:41:11.428789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100940499s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-26T00:41:11.428958Z","caller":"traceutil/trace.go:171","msg":"trace[535626202] linearizableReadLoop","detail":"{readStateIndex:259; appliedIndex:258; }","duration":"1.450147285s","start":"2026-04-26T00:41:09.978798Z","end":"2026-04-26T00:41:11.428945Z","steps":["trace[535626202] 'read index received' (duration: 9.487571ms)","trace[535626202] 'applied index is now lower than readState.Index' (duration: 1.440658994s)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:41:11.428964Z","caller":"traceutil/trace.go:171","msg":"trace[226195051] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"1.449953148s","start":"2026-04-26T00:41:09.978999Z","end":"2026-04-26T00:41:11.428952Z","steps":["trace[226195051] 'process raft request' (duration: 1.449867835s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:11.429023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:09.978969Z","time spent":"1.450020801s","remote":"127.0.0.1:33596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T00:41:11.429056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.450253279s","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-26T00:41:11.429074Z","caller":"traceutil/trace.go:171","msg":"trace[1433559482] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:255; }","duration":"1.45028643s","start":"2026-04-26T00:41:09.978782Z","end":"2026-04-26T00:41:11.429069Z","steps":["trace[1433559482] 'agreement among raft nodes before linearized reading' (duration: 1.450220888s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:11.429091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:09.978774Z","time spent":"1.450312901s","remote":"127.0.0.1:33650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-26T00:41:11.429148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304382691s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-26T00:41:11.429153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438147794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-26T00:41:11.429217Z","caller":"traceutil/trace.go:171","msg":"trace[867289857] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:255; }","duration":"1.304486115s","start":"2026-04-26T00:41:10.124713Z","end":"2026-04-26T00:41:11.429199Z","steps":["trace[867289857] 'agreement among raft nodes before linearized reading' (duration: 1.3043436s)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:11.42923Z","caller":"traceutil/trace.go:171","msg":"trace[510521545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:255; }","duration":"1.438259607s","start":"2026-04-26T00:41:09.99095Z","end":"2026-04-26T00:41:11.42921Z","steps":["trace[510521545] 'agreement among raft nodes before linearized reading' (duration: 1.438097002s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:11.429259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:10.1247Z","time spent":"1.304550677s","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-26T00:41:11.429266Z","caller":"traceutil/trace.go:171","msg":"trace[1093603701] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"1.451051247s","start":"2026-04-26T00:41:09.978204Z","end":"2026-04-26T00:41:11.429255Z","steps":["trace[1093603701] 'process raft request' (duration: 349.587539ms)","trace[1093603701] 'compare' (duration: 1.100829156s)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:11.429283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:09.990941Z","time spent":"1.43833098s","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-26T00:41:11.429317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:09.978182Z","time spent":"1.451116109s","remote":"127.0.0.1:33864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":481,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T00:41:11.429618Z","caller":"traceutil/trace.go:171","msg":"trace[53014222] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"1.45057672s","start":"2026-04-26T00:41:09.979023Z","end":"2026-04-26T00:41:11.4296Z","steps":["trace[53014222] 'process raft request' (duration: 1.449888766s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:11.429711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:09.979009Z","time spent":"1.450646292s","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T00:41:11.658361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.199211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a9c14ba76c4e4e\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-26T00:41:11.658423Z","caller":"traceutil/trace.go:171","msg":"trace[56991233] range","detail":"{range_begin:/registry/events/default/instance.18a9c14ba76c4e4e; range_end:; }","duration":"225.312775ms","start":"2026-04-26T00:41:11.433096Z","end":"2026-04-26T00:41:11.658409Z","steps":["trace[56991233] 'agreement among raft nodes before linearized reading' (duration: 225.235952ms)"],"step_count":1} 2026/04/26 00:41:11 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-26T00:41:11.933802Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291073999179859,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-26T00:41:12.434475Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291073999179859,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-26T00:41:12.82744Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.394439257s","expected-duration":"1s"} {"level":"info","ts":"2026-04-26T00:41:12.865027Z","caller":"traceutil/trace.go:171","msg":"trace[1545959477] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"1.432098402s","start":"2026-04-26T00:41:11.432901Z","end":"2026-04-26T00:41:12.864999Z","steps":["trace[1545959477] 'process raft request' (duration: 1.39484178s)","trace[1545959477] 'compare' (duration: 37.109647ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:12.865184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:11.43288Z","time spent":"1.432222346s","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T00:41:13.440247Z","caller":"traceutil/trace.go:171","msg":"trace[666125735] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"2.006694951s","start":"2026-04-26T00:41:11.43353Z","end":"2026-04-26T00:41:13.440225Z","steps":["trace[666125735] 'process raft request' (duration: 1.934254958s)","trace[666125735] 'compare' (duration: 72.137003ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:41:13.44046Z","caller":"traceutil/trace.go:171","msg":"trace[1431546133] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"1.630722738s","start":"2026-04-26T00:41:11.809687Z","end":"2026-04-26T00:41:13.440409Z","steps":["trace[1431546133] 'process raft request' (duration: 1.630598604s)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:13.440528Z","caller":"traceutil/trace.go:171","msg":"trace[788951579] linearizableReadLoop","detail":"{readStateIndex:271; appliedIndex:261; }","duration":"2.007364304s","start":"2026-04-26T00:41:11.433149Z","end":"2026-04-26T00:41:13.440513Z","steps":["trace[788951579] 'read index received' (duration: 1.394611683s)","trace[788951579] 'applied index is now lower than readState.Index' (duration: 612.751851ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:41:13.44057Z","caller":"traceutil/trace.go:171","msg":"trace[6534843] transaction","detail":"{read_only:false; number_of_response:0; response_revision:259; }","duration":"1.536207463s","start":"2026-04-26T00:41:11.904356Z","end":"2026-04-26T00:41:13.440563Z","steps":["trace[6534843] 'process raft request' (duration: 1.535988946s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:13.440983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:11.433514Z","time spent":"2.007383564s","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T00:41:13.441576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:11.904332Z","time spent":"1.537209597s","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T00:41:13.441869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.713668868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:13.441937Z","caller":"traceutil/trace.go:171","msg":"trace[707667190] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:263; }","duration":"1.713763402s","start":"2026-04-26T00:41:11.728156Z","end":"2026-04-26T00:41:13.441919Z","steps":["trace[707667190] 'agreement among raft nodes before linearized reading' (duration: 1.713644258s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:13.441913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:11.809676Z","time spent":"1.632142497s","remote":"127.0.0.1:33596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T00:41:13.441984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:11.728143Z","time spent":"1.713827813s","remote":"127.0.0.1:34010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" "} {"level":"info","ts":"2026-04-26T00:41:13.440595Z","caller":"traceutil/trace.go:171","msg":"trace[659275470] transaction","detail":"{read_only:false; number_of_response:0; response_revision:259; }","duration":"1.514041805s","start":"2026-04-26T00:41:11.926548Z","end":"2026-04-26T00:41:13.44059Z","steps":["trace[659275470] 'process raft request' (duration: 1.513824758s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:13.442058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.632684086s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-04-26T00:41:13.442076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003297933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T00:41:13.442087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.633563448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T00:41:13.442098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:11.926524Z","time spent":"1.515518967s","remote":"127.0.0.1:33700","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T00:41:13.442128Z","caller":"traceutil/trace.go:171","msg":"trace[967302973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:263; }","duration":"1.003384496s","start":"2026-04-26T00:41:12.438727Z","end":"2026-04-26T00:41:13.442111Z","steps":["trace[967302973] 'agreement among raft nodes before linearized reading' (duration: 1.00323298s)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:13.442138Z","caller":"traceutil/trace.go:171","msg":"trace[1824658792] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:263; }","duration":"1.633645451s","start":"2026-04-26T00:41:11.808481Z","end":"2026-04-26T00:41:13.442126Z","steps":["trace[1824658792] 'agreement among raft nodes before linearized reading' (duration: 1.633545726s)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:13.442105Z","caller":"traceutil/trace.go:171","msg":"trace[1808216256] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:263; }","duration":"1.632791532s","start":"2026-04-26T00:41:11.809302Z","end":"2026-04-26T00:41:13.442094Z","steps":["trace[1808216256] 'agreement among raft nodes before linearized reading' (duration: 1.632654616s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:13.442171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:11.808465Z","time spent":"1.633697262s","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-04-26T00:41:13.442169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:12.4387Z","time spent":"1.003458889s","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-26T00:41:13.442195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:11.809297Z","time spent":"1.632886505s","remote":"127.0.0.1:33920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-26T00:41:13.440647Z","caller":"traceutil/trace.go:171","msg":"trace[82007237] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"1.51275973s","start":"2026-04-26T00:41:11.927872Z","end":"2026-04-26T00:41:13.440632Z","steps":["trace[82007237] 'process raft request' (duration: 1.512561273s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:13.442272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:11.92786Z","time spent":"1.514391427s","remote":"127.0.0.1:33700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T00:41:13.440689Z","caller":"traceutil/trace.go:171","msg":"trace[1388731476] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"1.513999104s","start":"2026-04-26T00:41:11.926679Z","end":"2026-04-26T00:41:13.440678Z","steps":["trace[1388731476] 'process raft request' (duration: 1.513709513s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:13.442467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:11.926664Z","time spent":"1.515700183s","remote":"127.0.0.1:33700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T00:41:13.440708Z","caller":"traceutil/trace.go:171","msg":"trace[1476566154] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"1.51274369s","start":"2026-04-26T00:41:11.927955Z","end":"2026-04-26T00:41:13.440698Z","steps":["trace[1476566154] 'process raft request' (duration: 1.512504211s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:13.442691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:11.927942Z","time spent":"1.514727708s","remote":"127.0.0.1:33700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T00:41:13.440733Z","caller":"traceutil/trace.go:171","msg":"trace[1378559774] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"1.51271803s","start":"2026-04-26T00:41:11.928008Z","end":"2026-04-26T00:41:13.440726Z","steps":["trace[1378559774] 'process raft request' (duration: 1.512474971s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:13.442851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:11.928001Z","time spent":"1.514828291s","remote":"127.0.0.1:33700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T00:41:13.440848Z","caller":"traceutil/trace.go:171","msg":"trace[1793680726] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"2.006866977s","start":"2026-04-26T00:41:11.43395Z","end":"2026-04-26T00:41:13.440817Z","steps":["trace[1793680726] 'process raft request' (duration: 2.006194543s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:13.443017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:11.433939Z","time spent":"2.009057741s","remote":"127.0.0.1:33712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T00:41:13.682544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.574413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T00:41:13.682694Z","caller":"traceutil/trace.go:171","msg":"trace[268481131] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"193.875065ms","start":"2026-04-26T00:41:13.488804Z","end":"2026-04-26T00:41:13.682679Z","steps":["trace[268481131] 'process raft request' (duration: 193.828113ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:13.682845Z","caller":"traceutil/trace.go:171","msg":"trace[2050140477] linearizableReadLoop","detail":"{readStateIndex:288; appliedIndex:287; }","duration":"199.467366ms","start":"2026-04-26T00:41:13.483348Z","end":"2026-04-26T00:41:13.682815Z","steps":["trace[2050140477] 'read index received' (duration: 59.658466ms)","trace[2050140477] 'applied index is now lower than readState.Index' (duration: 139.80654ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:41:13.68301Z","caller":"traceutil/trace.go:171","msg":"trace[563245791] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"201.509916ms","start":"2026-04-26T00:41:13.481484Z","end":"2026-04-26T00:41:13.682994Z","steps":["trace[563245791] 'process raft request' (duration: 61.400254ms)","trace[563245791] 'compare' (duration: 139.423298ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:13.683029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.641753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:13.68311Z","caller":"traceutil/trace.go:171","msg":"trace[977978867] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:281; }","duration":"199.736386ms","start":"2026-04-26T00:41:13.483332Z","end":"2026-04-26T00:41:13.683069Z","steps":["trace[977978867] 'agreement among raft nodes before linearized reading' (duration: 199.595901ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:13.984263Z","caller":"traceutil/trace.go:171","msg":"trace[368817539] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"116.244214ms","start":"2026-04-26T00:41:13.868Z","end":"2026-04-26T00:41:13.984244Z","steps":["trace[368817539] 'process raft request' (duration: 100.826076ms)","trace[368817539] 'compare' (duration: 15.067316ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:14.922696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.3517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:14.922769Z","caller":"traceutil/trace.go:171","msg":"trace[789784754] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:0; response_revision:311; }","duration":"175.475395ms","start":"2026-04-26T00:41:14.747276Z","end":"2026-04-26T00:41:14.922752Z","steps":["trace[789784754] 'range keys from in-memory index tree' (duration: 175.269967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:16.651418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.961956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-26T00:41:16.651554Z","caller":"traceutil/trace.go:171","msg":"trace[337120009] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"473.564166ms","start":"2026-04-26T00:41:16.177965Z","end":"2026-04-26T00:41:16.651529Z","steps":["trace[337120009] 'process raft request' (duration: 116.426444ms)","trace[337120009] 'compare' (duration: 356.787249ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:16.651625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:16.177948Z","time spent":"473.649239ms","remote":"127.0.0.1:33712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-26T00:41:16.660101Z","caller":"traceutil/trace.go:171","msg":"trace[909173335] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"480.268308ms","start":"2026-04-26T00:41:16.179804Z","end":"2026-04-26T00:41:16.660072Z","steps":["trace[909173335] 'process raft request' (duration: 480.034651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:16.660274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:16.179794Z","time spent":"480.395742ms","remote":"127.0.0.1:33700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4781,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T00:41:16.660348Z","caller":"traceutil/trace.go:171","msg":"trace[1634874647] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:347; }","duration":"479.40993ms","start":"2026-04-26T00:41:16.180915Z","end":"2026-04-26T00:41:16.660325Z","steps":["trace[1634874647] 'read index received' (duration: 113.471607ms)","trace[1634874647] 'applied index is now lower than readState.Index' (duration: 365.937353ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:41:16.66044Z","caller":"traceutil/trace.go:171","msg":"trace[294362171] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"480.478895ms","start":"2026-04-26T00:41:16.179949Z","end":"2026-04-26T00:41:16.660428Z","steps":["trace[294362171] 'process raft request' (duration: 480.072002ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:16.660463Z","caller":"traceutil/trace.go:171","msg":"trace[1282189259] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"478.4963ms","start":"2026-04-26T00:41:16.18195Z","end":"2026-04-26T00:41:16.660446Z","steps":["trace[1282189259] 'process raft request' (duration: 478.281183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:16.660519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:16.179943Z","time spent":"480.527686ms","remote":"127.0.0.1:33596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T00:41:16.660554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:16.181943Z","time spent":"478.558041ms","remote":"127.0.0.1:33880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T00:41:16.660727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.805502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-04-26T00:41:16.660768Z","caller":"traceutil/trace.go:171","msg":"trace[1478162154] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:343; }","duration":"479.869974ms","start":"2026-04-26T00:41:16.180887Z","end":"2026-04-26T00:41:16.660757Z","steps":["trace[1478162154] 'agreement among raft nodes before linearized reading' (duration: 479.802261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:16.660799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:16.180881Z","time spent":"479.912116ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2923,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-04-26T00:41:16.706818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.855648ms","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-26T00:41:16.706914Z","caller":"traceutil/trace.go:171","msg":"trace[737377910] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:344; }","duration":"509.974341ms","start":"2026-04-26T00:41:16.196918Z","end":"2026-04-26T00:41:16.706893Z","steps":["trace[737377910] 'agreement among raft nodes before linearized reading' (duration: 509.793655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:16.706958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.380977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-26T00:41:16.707071Z","caller":"traceutil/trace.go:171","msg":"trace[917611279] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:344; }","duration":"459.607564ms","start":"2026-04-26T00:41:16.247436Z","end":"2026-04-26T00:41:16.707044Z","steps":["trace[917611279] 'agreement among raft nodes before linearized reading' (duration: 459.352266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:16.707564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:16.247421Z","time spent":"460.132752ms","remote":"127.0.0.1:33712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-04-26T00:41:16.706994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:16.196913Z","time spent":"510.039823ms","remote":"127.0.0.1:33712","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":"warn","ts":"2026-04-26T00:41:16.707069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.1274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-04-26T00:41:16.707797Z","caller":"traceutil/trace.go:171","msg":"trace[1727671602] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:344; }","duration":"479.892936ms","start":"2026-04-26T00:41:16.227894Z","end":"2026-04-26T00:41:16.707787Z","steps":["trace[1727671602] 'agreement among raft nodes before linearized reading' (duration: 479.13134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:16.707845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:16.227876Z","time spent":"479.960018ms","remote":"127.0.0.1:33712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":207,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-04-26T00:41:16.706829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.264107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-26T00:41:16.70798Z","caller":"traceutil/trace.go:171","msg":"trace[2071167065] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:344; }","duration":"410.409085ms","start":"2026-04-26T00:41:16.297548Z","end":"2026-04-26T00:41:16.707957Z","steps":["trace[2071167065] 'agreement among raft nodes before linearized reading' (duration: 409.214896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:16.708045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:16.297484Z","time spent":"410.5453ms","remote":"127.0.0.1:33712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-04-26T00:41:17.877136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.443725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T00:41:17.877293Z","caller":"traceutil/trace.go:171","msg":"trace[1231094593] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:374; }","duration":"317.891522ms","start":"2026-04-26T00:41:17.559386Z","end":"2026-04-26T00:41:17.877278Z","steps":["trace[1231094593] 'read index received' (duration: 12.242671ms)","trace[1231094593] 'applied index is now lower than readState.Index' (duration: 305.647921ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:17.877361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.981636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-26T00:41:17.877392Z","caller":"traceutil/trace.go:171","msg":"trace[701231674] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:368; }","duration":"318.050508ms","start":"2026-04-26T00:41:17.559332Z","end":"2026-04-26T00:41:17.877382Z","steps":["trace[701231674] 'agreement among raft nodes before linearized reading' (duration: 317.996846ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:17.877367Z","caller":"traceutil/trace.go:171","msg":"trace[118707410] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"357.21165ms","start":"2026-04-26T00:41:17.520109Z","end":"2026-04-26T00:41:17.877321Z","steps":["trace[118707410] 'process raft request' (duration: 51.510066ms)","trace[118707410] 'compare' (duration: 305.330262ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:17.87743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:17.559312Z","time spent":"318.11086ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.157\" "} {"level":"warn","ts":"2026-04-26T00:41:17.877541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:17.520092Z","time spent":"357.353064ms","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T00:41:17.877381Z","caller":"traceutil/trace.go:171","msg":"trace[1175313402] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"356.390652ms","start":"2026-04-26T00:41:17.520964Z","end":"2026-04-26T00:41:17.877355Z","steps":["trace[1175313402] 'process raft request' (duration: 356.266228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:17.877695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:17.520924Z","time spent":"356.681842ms","remote":"127.0.0.1:33780","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-26T00:41:18.342238Z","caller":"traceutil/trace.go:171","msg":"trace[40710296] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"168.881103ms","start":"2026-04-26T00:41:18.173333Z","end":"2026-04-26T00:41:18.342214Z","steps":["trace[40710296] 'process raft request' (duration: 168.719988ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:19.010761Z","caller":"traceutil/trace.go:171","msg":"trace[1623622646] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"129.196647ms","start":"2026-04-26T00:41:18.881546Z","end":"2026-04-26T00:41:19.010743Z","steps":["trace[1623622646] 'process raft request' (duration: 129.055303ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:20.234177Z","caller":"traceutil/trace.go:171","msg":"trace[265160763] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"296.21166ms","start":"2026-04-26T00:41:19.937929Z","end":"2026-04-26T00:41:20.23414Z","steps":["trace[265160763] 'process raft request' (duration: 257.851667ms)","trace[265160763] 'compare' (duration: 38.25392ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:22.87432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.059077ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-26T00:41:34.147187Z","caller":"traceutil/trace.go:171","msg":"trace[1299186946] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"169.67531ms","start":"2026-04-26T00:41:33.977487Z","end":"2026-04-26T00:41:34.147163Z","steps":["trace[1299186946] 'read index received' (duration: 169.491085ms)","trace[1299186946] 'applied index is now lower than readState.Index' (duration: 183.335µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:41:34.147236Z","caller":"traceutil/trace.go:171","msg":"trace[722654776] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"181.140914ms","start":"2026-04-26T00:41:33.966062Z","end":"2026-04-26T00:41:34.147203Z","steps":["trace[722654776] 'process raft request' (duration: 180.967519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:34.147676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.175574ms","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-26T00:41:34.147733Z","caller":"traceutil/trace.go:171","msg":"trace[473928784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:483; }","duration":"170.279146ms","start":"2026-04-26T00:41:33.977439Z","end":"2026-04-26T00:41:34.147718Z","steps":["trace[473928784] 'agreement among raft nodes before linearized reading' (duration: 169.835454ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:34.285811Z","caller":"traceutil/trace.go:171","msg":"trace[143623507] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"134.278313ms","start":"2026-04-26T00:41:34.151509Z","end":"2026-04-26T00:41:34.285787Z","steps":["trace[143623507] 'process raft request' (duration: 46.809671ms)","trace[143623507] 'compare' (duration: 87.257776ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:41:34.286109Z","caller":"traceutil/trace.go:171","msg":"trace[2002010733] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"134.52972ms","start":"2026-04-26T00:41:34.151552Z","end":"2026-04-26T00:41:34.286081Z","steps":["trace[2002010733] 'process raft request' (duration: 134.168499ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:34.672941Z","caller":"traceutil/trace.go:171","msg":"trace[152014750] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"134.301463ms","start":"2026-04-26T00:41:34.538616Z","end":"2026-04-26T00:41:34.672917Z","steps":["trace[152014750] 'process raft request' (duration: 134.217101ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:34.672965Z","caller":"traceutil/trace.go:171","msg":"trace[839022862] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"136.73991ms","start":"2026-04-26T00:41:34.536175Z","end":"2026-04-26T00:41:34.672915Z","steps":["trace[839022862] 'process raft request' (duration: 136.567825ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:34.673231Z","caller":"traceutil/trace.go:171","msg":"trace[618843125] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"136.917365ms","start":"2026-04-26T00:41:34.536219Z","end":"2026-04-26T00:41:34.673136Z","steps":["trace[618843125] 'process raft request' (duration: 136.568365ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:34.673299Z","caller":"traceutil/trace.go:171","msg":"trace[156888060] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"141.920559ms","start":"2026-04-26T00:41:34.531148Z","end":"2026-04-26T00:41:34.673069Z","steps":["trace[156888060] 'process raft request' (duration: 141.444166ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:34.679818Z","caller":"traceutil/trace.go:171","msg":"trace[863282699] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"139.562586ms","start":"2026-04-26T00:41:34.540235Z","end":"2026-04-26T00:41:34.679798Z","steps":["trace[863282699] 'process raft request' (duration: 139.425782ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:34.68012Z","caller":"traceutil/trace.go:171","msg":"trace[508772800] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"139.153614ms","start":"2026-04-26T00:41:34.540952Z","end":"2026-04-26T00:41:34.680105Z","steps":["trace[508772800] 'process raft request' (duration: 138.809865ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:34.680247Z","caller":"traceutil/trace.go:171","msg":"trace[18919200] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"137.773687ms","start":"2026-04-26T00:41:34.542463Z","end":"2026-04-26T00:41:34.680236Z","steps":["trace[18919200] 'process raft request' (duration: 137.50014ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:34.68033Z","caller":"traceutil/trace.go:171","msg":"trace[1284947643] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"127.815736ms","start":"2026-04-26T00:41:34.552506Z","end":"2026-04-26T00:41:34.680322Z","steps":["trace[1284947643] 'process raft request' (duration: 127.606651ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:34.680386Z","caller":"traceutil/trace.go:171","msg":"trace[73806796] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"122.849142ms","start":"2026-04-26T00:41:34.557509Z","end":"2026-04-26T00:41:34.680359Z","steps":["trace[73806796] 'process raft request' (duration: 122.652307ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:34.68044Z","caller":"traceutil/trace.go:171","msg":"trace[371028131] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"134.551109ms","start":"2026-04-26T00:41:34.545881Z","end":"2026-04-26T00:41:34.680432Z","steps":["trace[371028131] 'process raft request' (duration: 134.141529ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:35.717629Z","caller":"traceutil/trace.go:171","msg":"trace[1079524216] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"488.927684ms","start":"2026-04-26T00:41:35.228675Z","end":"2026-04-26T00:41:35.717603Z","steps":["trace[1079524216] 'process raft request' (duration: 488.659807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:35.717812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:35.228658Z","time spent":"489.045686ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T00:41:36.214748Z","caller":"traceutil/trace.go:171","msg":"trace[420294665] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"971.724272ms","start":"2026-04-26T00:41:35.242981Z","end":"2026-04-26T00:41:36.214705Z","steps":["trace[420294665] 'process raft request' (duration: 967.462489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:36.214926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:35.242963Z","time spent":"971.874736ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T00:41:36.215654Z","caller":"traceutil/trace.go:171","msg":"trace[617670922] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:561; }","duration":"760.592706ms","start":"2026-04-26T00:41:35.455033Z","end":"2026-04-26T00:41:36.215626Z","steps":["trace[617670922] 'read index received' (duration: 262.387824ms)","trace[617670922] 'applied index is now lower than readState.Index' (duration: 498.203802ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:41:36.215784Z","caller":"traceutil/trace.go:171","msg":"trace[2075593196] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"828.067407ms","start":"2026-04-26T00:41:35.38769Z","end":"2026-04-26T00:41:36.215757Z","steps":["trace[2075593196] 'process raft request' (duration: 827.352447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:36.215875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:35.387666Z","time spent":"828.17209ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T00:41:36.216144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.086539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:36.216209Z","caller":"traceutil/trace.go:171","msg":"trace[2000955942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:548; }","duration":"761.172921ms","start":"2026-04-26T00:41:35.455018Z","end":"2026-04-26T00:41:36.216191Z","steps":["trace[2000955942] 'agreement among raft nodes before linearized reading' (duration: 760.830402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:36.216252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:35.454952Z","time spent":"761.290924ms","remote":"127.0.0.1:33490","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-26T00:41:36.235406Z","caller":"traceutil/trace.go:171","msg":"trace[1659389605] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"493.554392ms","start":"2026-04-26T00:41:35.74182Z","end":"2026-04-26T00:41:36.235375Z","steps":["trace[1659389605] 'process raft request' (duration: 493.404298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:36.235518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:35.741743Z","time spent":"493.709776ms","remote":"127.0.0.1:33580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T00:41:36.235539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.687659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-26T00:41:36.235717Z","caller":"traceutil/trace.go:171","msg":"trace[564690277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:549; }","duration":"476.897065ms","start":"2026-04-26T00:41:35.758743Z","end":"2026-04-26T00:41:36.23564Z","steps":["trace[564690277] 'agreement among raft nodes before linearized reading' (duration: 476.646419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:36.235848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:35.758723Z","time spent":"477.07934ms","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-26T00:41:36.337276Z","caller":"traceutil/trace.go:171","msg":"trace[1511219368] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:565; }","duration":"101.911096ms","start":"2026-04-26T00:41:36.235351Z","end":"2026-04-26T00:41:36.337262Z","steps":["trace[1511219368] 'read index received' (duration: 94.307715ms)","trace[1511219368] 'applied index is now lower than readState.Index' (duration: 7.602881ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:41:36.337414Z","caller":"traceutil/trace.go:171","msg":"trace[725198532] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"107.145177ms","start":"2026-04-26T00:41:36.23025Z","end":"2026-04-26T00:41:36.337395Z","steps":["trace[725198532] 'process raft request' (duration: 99.39909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:36.337494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.122244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:36.337533Z","caller":"traceutil/trace.go:171","msg":"trace[1886882303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"119.223797ms","start":"2026-04-26T00:41:36.218302Z","end":"2026-04-26T00:41:36.337526Z","steps":["trace[1886882303] 'agreement among raft nodes before linearized reading' (duration: 119.137925ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:36.958459Z","caller":"traceutil/trace.go:171","msg":"trace[966323412] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"163.806081ms","start":"2026-04-26T00:41:36.794627Z","end":"2026-04-26T00:41:36.958433Z","steps":["trace[966323412] 'read index received' (duration: 163.419081ms)","trace[966323412] 'applied index is now lower than readState.Index' (duration: 385.86µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:41:36.95857Z","caller":"traceutil/trace.go:171","msg":"trace[1944945611] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"184.233933ms","start":"2026-04-26T00:41:36.774292Z","end":"2026-04-26T00:41:36.958526Z","steps":["trace[1944945611] 'process raft request' (duration: 183.620897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:36.958692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.054937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:74769"} {"level":"info","ts":"2026-04-26T00:41:36.958737Z","caller":"traceutil/trace.go:171","msg":"trace[919429725] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:563; }","duration":"164.217812ms","start":"2026-04-26T00:41:36.794505Z","end":"2026-04-26T00:41:36.958723Z","steps":["trace[919429725] 'agreement among raft nodes before linearized reading' (duration: 164.029857ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:42.210331Z","caller":"traceutil/trace.go:171","msg":"trace[83142486] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"214.58657ms","start":"2026-04-26T00:41:41.995713Z","end":"2026-04-26T00:41:42.2103Z","steps":["trace[83142486] 'process raft request' (duration: 213.940204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:43.132759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.310437ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-26T00:41:43.132898Z","caller":"traceutil/trace.go:171","msg":"trace[845329310] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"217.429996ms","start":"2026-04-26T00:41:42.915434Z","end":"2026-04-26T00:41:43.132864Z","steps":["trace[845329310] 'read index received' (duration: 37.28µs)","trace[845329310] 'applied index is now lower than readState.Index' (duration: 217.390815ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:43.133173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.730383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ktjbm\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-26T00:41:43.133229Z","caller":"traceutil/trace.go:171","msg":"trace[1235199482] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ktjbm; range_end:; response_count:1; response_revision:604; }","duration":"217.795025ms","start":"2026-04-26T00:41:42.91542Z","end":"2026-04-26T00:41:43.133215Z","steps":["trace[1235199482] 'agreement among raft nodes before linearized reading' (duration: 217.557419ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:43.613299Z","caller":"traceutil/trace.go:171","msg":"trace[1186228541] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"458.320198ms","start":"2026-04-26T00:41:43.154957Z","end":"2026-04-26T00:41:43.613277Z","steps":["trace[1186228541] 'read index received' (duration: 458.063081ms)","trace[1186228541] 'applied index is now lower than readState.Index' (duration: 256.277µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:41:43.613411Z","caller":"traceutil/trace.go:171","msg":"trace[604845600] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"463.924696ms","start":"2026-04-26T00:41:43.149452Z","end":"2026-04-26T00:41:43.613377Z","steps":["trace[604845600] 'process raft request' (duration: 463.633249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:43.613473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.254222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:43.613513Z","caller":"traceutil/trace.go:171","msg":"trace[776502002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"158.346064ms","start":"2026-04-26T00:41:43.455156Z","end":"2026-04-26T00:41:43.613502Z","steps":["trace[776502002] 'agreement among raft nodes before linearized reading' (duration: 158.245002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:43.613539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.573923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-26T00:41:43.613573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:43.149429Z","time spent":"464.05883ms","remote":"127.0.0.1:33700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T00:41:43.613596Z","caller":"traceutil/trace.go:171","msg":"trace[1265018136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"458.637215ms","start":"2026-04-26T00:41:43.154947Z","end":"2026-04-26T00:41:43.613584Z","steps":["trace[1265018136] 'agreement among raft nodes before linearized reading' (duration: 458.457931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:43.613638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:43.154908Z","time spent":"458.721767ms","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-26T00:41:44.882953Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291073999180513,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-26T00:41:45.383321Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291073999180513,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-26T00:41:45.792453Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.447501897s","expected-duration":"1s"} {"level":"info","ts":"2026-04-26T00:41:45.792744Z","caller":"traceutil/trace.go:171","msg":"trace[1273195947] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"1.410623995s","start":"2026-04-26T00:41:44.382099Z","end":"2026-04-26T00:41:45.792723Z","steps":["trace[1273195947] 'read index received' (duration: 1.410469031s)","trace[1273195947] 'applied index is now lower than readState.Index' (duration: 154.444µs)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:45.79291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.410812989s","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-26T00:41:45.792935Z","caller":"traceutil/trace.go:171","msg":"trace[1350050531] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"1.410887651s","start":"2026-04-26T00:41:44.382039Z","end":"2026-04-26T00:41:45.792927Z","steps":["trace[1350050531] 'agreement among raft nodes before linearized reading' (duration: 1.410746067s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:45.792959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:44.382026Z","time spent":"1.410928211s","remote":"127.0.0.1:33780","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":"info","ts":"2026-04-26T00:41:45.792889Z","caller":"traceutil/trace.go:171","msg":"trace[1702565219] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"1.448004939s","start":"2026-04-26T00:41:44.344851Z","end":"2026-04-26T00:41:45.792856Z","steps":["trace[1702565219] 'process raft request' (duration: 1.447765053s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:45.793026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.60059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T00:41:45.793041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.409411925s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-26T00:41:45.793138Z","caller":"traceutil/trace.go:171","msg":"trace[1121632587] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:607; }","duration":"186.723123ms","start":"2026-04-26T00:41:45.606371Z","end":"2026-04-26T00:41:45.793094Z","steps":["trace[1121632587] 'agreement among raft nodes before linearized reading' (duration: 186.555199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:45.7932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.772758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:45.793232Z","caller":"traceutil/trace.go:171","msg":"trace[1520153390] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:607; }","duration":"234.803488ms","start":"2026-04-26T00:41:45.558419Z","end":"2026-04-26T00:41:45.793223Z","steps":["trace[1520153390] 'agreement among raft nodes before linearized reading' (duration: 234.760167ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:45.79327Z","caller":"traceutil/trace.go:171","msg":"trace[174953945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"1.409564948s","start":"2026-04-26T00:41:44.383564Z","end":"2026-04-26T00:41:45.793129Z","steps":["trace[174953945] 'agreement among raft nodes before linearized reading' (duration: 1.409332843s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:45.793327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.196839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:45.793352Z","caller":"traceutil/trace.go:171","msg":"trace[31201382] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:607; }","duration":"214.22508ms","start":"2026-04-26T00:41:45.579117Z","end":"2026-04-26T00:41:45.793342Z","steps":["trace[31201382] 'agreement among raft nodes before linearized reading' (duration: 214.19688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:45.79334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.710914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T00:41:45.793432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.666651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:45.793435Z","caller":"traceutil/trace.go:171","msg":"trace[217378867] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:607; }","duration":"158.853138ms","start":"2026-04-26T00:41:45.634561Z","end":"2026-04-26T00:41:45.793414Z","steps":["trace[217378867] 'agreement among raft nodes before linearized reading' (duration: 158.664424ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:45.793465Z","caller":"traceutil/trace.go:171","msg":"trace[516226324] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:607; }","duration":"214.775193ms","start":"2026-04-26T00:41:45.578683Z","end":"2026-04-26T00:41:45.793458Z","steps":["trace[516226324] 'agreement among raft nodes before linearized reading' (duration: 214.706041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:45.793469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.74285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:45.793494Z","caller":"traceutil/trace.go:171","msg":"trace[1019061106] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:607; }","duration":"159.768131ms","start":"2026-04-26T00:41:45.633718Z","end":"2026-04-26T00:41:45.793486Z","steps":["trace[1019061106] 'agreement among raft nodes before linearized reading' (duration: 159.7323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:45.793523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.099162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:45.793543Z","caller":"traceutil/trace.go:171","msg":"trace[324103334] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:607; }","duration":"187.136143ms","start":"2026-04-26T00:41:45.606399Z","end":"2026-04-26T00:41:45.793536Z","steps":["trace[324103334] 'agreement among raft nodes before linearized reading' (duration: 187.110332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:45.793562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.576942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:45.793582Z","caller":"traceutil/trace.go:171","msg":"trace[71614858] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:607; }","duration":"234.627784ms","start":"2026-04-26T00:41:45.558948Z","end":"2026-04-26T00:41:45.793576Z","steps":["trace[71614858] 'agreement among raft nodes before linearized reading' (duration: 234.596003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:45.793618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.192871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T00:41:45.793608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.33313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:45.793654Z","caller":"traceutil/trace.go:171","msg":"trace[978501400] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:607; }","duration":"287.396512ms","start":"2026-04-26T00:41:45.506251Z","end":"2026-04-26T00:41:45.793648Z","steps":["trace[978501400] 'agreement among raft nodes before linearized reading' (duration: 287.340631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:45.79334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:44.383545Z","time spent":"1.409779323s","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-26T00:41:45.793717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.532512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T00:41:45.793293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.496576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T00:41:45.793666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.482404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T00:41:45.793939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.652759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T00:41:45.793948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.394541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-26T00:41:45.79397Z","caller":"traceutil/trace.go:171","msg":"trace[1841337547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:607; }","duration":"109.415992ms","start":"2026-04-26T00:41:45.68455Z","end":"2026-04-26T00:41:45.793966Z","steps":["trace[1841337547] 'agreement among raft nodes before linearized reading' (duration: 109.33176ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:45.79395Z","caller":"traceutil/trace.go:171","msg":"trace[10411658] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:607; }","duration":"235.788143ms","start":"2026-04-26T00:41:45.558143Z","end":"2026-04-26T00:41:45.793931Z","steps":["trace[10411658] 'agreement among raft nodes before linearized reading' (duration: 235.229029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:45.793975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.406212237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-26T00:41:45.793997Z","caller":"traceutil/trace.go:171","msg":"trace[1717951516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:607; }","duration":"1.406275498s","start":"2026-04-26T00:41:44.387716Z","end":"2026-04-26T00:41:45.793991Z","steps":["trace[1717951516] 'agreement among raft nodes before linearized reading' (duration: 1.406213087s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:45.7934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.586496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T00:41:45.794014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:44.387695Z","time spent":"1.406315069s","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-26T00:41:45.7938Z","caller":"traceutil/trace.go:171","msg":"trace[842614422] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:607; }","duration":"215.046169ms","start":"2026-04-26T00:41:45.578746Z","end":"2026-04-26T00:41:45.793792Z","steps":["trace[842614422] 'agreement among raft nodes before linearized reading' (duration: 214.491476ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:45.793632Z","caller":"traceutil/trace.go:171","msg":"trace[2146813532] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:607; }","duration":"186.209031ms","start":"2026-04-26T00:41:45.607419Z","end":"2026-04-26T00:41:45.793628Z","steps":["trace[2146813532] 'agreement among raft nodes before linearized reading' (duration: 186.18522ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:45.79397Z","caller":"traceutil/trace.go:171","msg":"trace[1890864974] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:607; }","duration":"287.710891ms","start":"2026-04-26T00:41:45.506251Z","end":"2026-04-26T00:41:45.793962Z","steps":["trace[1890864974] 'agreement among raft nodes before linearized reading' (duration: 287.66522ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:45.794016Z","caller":"traceutil/trace.go:171","msg":"trace[949288823] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:607; }","duration":"160.228192ms","start":"2026-04-26T00:41:45.633784Z","end":"2026-04-26T00:41:45.794012Z","steps":["trace[949288823] 'agreement among raft nodes before linearized reading' (duration: 159.591946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:45.793841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.333983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:45.7938Z","caller":"traceutil/trace.go:171","msg":"trace[473807858] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:607; }","duration":"104.629034ms","start":"2026-04-26T00:41:45.689158Z","end":"2026-04-26T00:41:45.793787Z","steps":["trace[473807858] 'agreement among raft nodes before linearized reading' (duration: 104.529961ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:45.794159Z","caller":"traceutil/trace.go:171","msg":"trace[661978309] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:607; }","duration":"105.65473ms","start":"2026-04-26T00:41:45.688499Z","end":"2026-04-26T00:41:45.794153Z","steps":["trace[661978309] 'agreement among raft nodes before linearized reading' (duration: 105.323572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:45.793863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.684611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:45.794204Z","caller":"traceutil/trace.go:171","msg":"trace[1552423227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"338.06361ms","start":"2026-04-26T00:41:45.456137Z","end":"2026-04-26T00:41:45.7942Z","steps":["trace[1552423227] 'agreement among raft nodes before linearized reading' (duration: 337.711102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:45.793881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.447795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:45.79424Z","caller":"traceutil/trace.go:171","msg":"trace[1384008804] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:607; }","duration":"105.855595ms","start":"2026-04-26T00:41:45.688375Z","end":"2026-04-26T00:41:45.794231Z","steps":["trace[1384008804] 'agreement among raft nodes before linearized reading' (duration: 105.485136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:45.794218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:45.45611Z","time spent":"338.102162ms","remote":"127.0.0.1:33490","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-26T00:41:45.793361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:44.344827Z","time spent":"1.448234384s","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T00:41:46.145741Z","caller":"traceutil/trace.go:171","msg":"trace[106127713] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"219.061242ms","start":"2026-04-26T00:41:45.926655Z","end":"2026-04-26T00:41:46.145716Z","steps":["trace[106127713] 'process raft request' (duration: 218.921359ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:46.241032Z","caller":"traceutil/trace.go:171","msg":"trace[1100504441] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:629; }","duration":"180.277735ms","start":"2026-04-26T00:41:46.060738Z","end":"2026-04-26T00:41:46.241016Z","steps":["trace[1100504441] 'read index received' (duration: 84.782494ms)","trace[1100504441] 'applied index is now lower than readState.Index' (duration: 95.494411ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:41:46.241165Z","caller":"traceutil/trace.go:171","msg":"trace[1681399560] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"301.843016ms","start":"2026-04-26T00:41:45.939305Z","end":"2026-04-26T00:41:46.241148Z","steps":["trace[1681399560] 'process raft request' (duration: 301.560989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:46.241284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:45.939275Z","time spent":"301.932858ms","remote":"127.0.0.1:33700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17232,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T00:41:46.241459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.638753ms","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-26T00:41:46.241528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.775086ms","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-26T00:41:46.241542Z","caller":"traceutil/trace.go:171","msg":"trace[1322009131] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:613; }","duration":"180.823558ms","start":"2026-04-26T00:41:46.060705Z","end":"2026-04-26T00:41:46.241528Z","steps":["trace[1322009131] 'agreement among raft nodes before linearized reading' (duration: 180.637303ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:46.241577Z","caller":"traceutil/trace.go:171","msg":"trace[2030421585] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:613; }","duration":"180.851327ms","start":"2026-04-26T00:41:46.060713Z","end":"2026-04-26T00:41:46.241565Z","steps":["trace[2030421585] 'agreement among raft nodes before linearized reading' (duration: 180.772776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:48.522363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.210801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:48.522428Z","caller":"traceutil/trace.go:171","msg":"trace[890255690] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:632; }","duration":"417.325424ms","start":"2026-04-26T00:41:48.105089Z","end":"2026-04-26T00:41:48.522414Z","steps":["trace[890255690] 'range keys from in-memory index tree' (duration: 417.101879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:48.522451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:41:48.105065Z","time spent":"417.380116ms","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-26T00:41:51.163677Z","caller":"traceutil/trace.go:171","msg":"trace[223627427] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"182.955705ms","start":"2026-04-26T00:41:50.980701Z","end":"2026-04-26T00:41:51.163657Z","steps":["trace[223627427] 'process raft request' (duration: 164.992285ms)","trace[223627427] 'compare' (duration: 17.844398ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:41:51.324268Z","caller":"traceutil/trace.go:171","msg":"trace[787845202] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"182.383167ms","start":"2026-04-26T00:41:51.141846Z","end":"2026-04-26T00:41:51.324229Z","steps":["trace[787845202] 'process raft request' (duration: 123.796559ms)","trace[787845202] 'compare' (duration: 58.427264ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:41:51.62724Z","caller":"traceutil/trace.go:171","msg":"trace[2001440955] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"230.757449ms","start":"2026-04-26T00:41:51.396454Z","end":"2026-04-26T00:41:51.627211Z","steps":["trace[2001440955] 'process raft request' (duration: 230.487663ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:51.627567Z","caller":"traceutil/trace.go:171","msg":"trace[647307370] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:700; }","duration":"172.650121ms","start":"2026-04-26T00:41:51.454891Z","end":"2026-04-26T00:41:51.627541Z","steps":["trace[647307370] 'read index received' (duration: 172.636231ms)","trace[647307370] 'applied index is now lower than readState.Index' (duration: 6.23µs)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:41:51.627706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.801034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:41:51.627783Z","caller":"traceutil/trace.go:171","msg":"trace[1613734346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"172.896907ms","start":"2026-04-26T00:41:51.454851Z","end":"2026-04-26T00:41:51.627748Z","steps":["trace[1613734346] 'agreement among raft nodes before linearized reading' (duration: 172.772294ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:51.866974Z","caller":"traceutil/trace.go:171","msg":"trace[1562494961] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"104.669412ms","start":"2026-04-26T00:41:51.762276Z","end":"2026-04-26T00:41:51.866945Z","steps":["trace[1562494961] 'process raft request' (duration: 104.483747ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:51.980377Z","caller":"traceutil/trace.go:171","msg":"trace[992684114] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"103.122526ms","start":"2026-04-26T00:41:51.877235Z","end":"2026-04-26T00:41:51.980358Z","steps":["trace[992684114] 'process raft request' (duration: 103.094596ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:51.980741Z","caller":"traceutil/trace.go:171","msg":"trace[1444327022] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"103.591167ms","start":"2026-04-26T00:41:51.877141Z","end":"2026-04-26T00:41:51.980733Z","steps":["trace[1444327022] 'process raft request' (duration: 103.065075ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:51.980842Z","caller":"traceutil/trace.go:171","msg":"trace[834726073] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"103.840792ms","start":"2026-04-26T00:41:51.876995Z","end":"2026-04-26T00:41:51.980836Z","steps":["trace[834726073] 'process raft request' (duration: 103.30611ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:52.048462Z","caller":"traceutil/trace.go:171","msg":"trace[2086560164] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:703; }","duration":"112.290567ms","start":"2026-04-26T00:41:51.936151Z","end":"2026-04-26T00:41:52.048442Z","steps":["trace[2086560164] 'read index received' (duration: 44.098618ms)","trace[2086560164] 'applied index is now lower than readState.Index' (duration: 68.190929ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:41:52.048687Z","caller":"traceutil/trace.go:171","msg":"trace[1545151757] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"117.707289ms","start":"2026-04-26T00:41:51.930958Z","end":"2026-04-26T00:41:52.048665Z","steps":["trace[1545151757] 'process raft request' (duration: 115.983101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:41:52.048796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.585193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-04-26T00:41:52.048862Z","caller":"traceutil/trace.go:171","msg":"trace[647240575] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:689; }","duration":"112.745786ms","start":"2026-04-26T00:41:51.936102Z","end":"2026-04-26T00:41:52.048848Z","steps":["trace[647240575] 'agreement among raft nodes before linearized reading' (duration: 112.508981ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:41:52.258354Z","caller":"traceutil/trace.go:171","msg":"trace[1547652160] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"115.819301ms","start":"2026-04-26T00:41:52.142488Z","end":"2026-04-26T00:41:52.258308Z","steps":["trace[1547652160] 'process raft request' (duration: 114.695315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:03.394149Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291073999180867,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-26T00:42:03.695962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064260837s","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-26T00:42:03.696154Z","caller":"traceutil/trace.go:171","msg":"trace[765521534] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"1.064463192s","start":"2026-04-26T00:42:02.631667Z","end":"2026-04-26T00:42:03.69613Z","steps":["trace[765521534] 'range keys from in-memory index tree' (duration: 1.064060213s)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:03.696247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:02.631621Z","time spent":"1.064607475s","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-26T00:42:03.709297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.89742ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-26T00:42:03.709481Z","caller":"traceutil/trace.go:171","msg":"trace[770603756] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:785; }","duration":"815.76113ms","start":"2026-04-26T00:42:02.893708Z","end":"2026-04-26T00:42:03.709469Z","steps":["trace[770603756] 'read index received' (duration: 678.640086ms)","trace[770603756] 'applied index is now lower than readState.Index' (duration: 137.120144ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:42:03.709525Z","caller":"traceutil/trace.go:171","msg":"trace[2124635782] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"679.446422ms","start":"2026-04-26T00:42:03.030056Z","end":"2026-04-26T00:42:03.709502Z","steps":["trace[2124635782] 'process raft request' (duration: 679.32563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:03.709567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.862441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-26T00:42:03.709622Z","caller":"traceutil/trace.go:171","msg":"trace[1816397805] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:764; }","duration":"815.968823ms","start":"2026-04-26T00:42:02.893642Z","end":"2026-04-26T00:42:03.709611Z","steps":["trace[1816397805] 'agreement among raft nodes before linearized reading' (duration: 815.875322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:03.709656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:02.893617Z","time spent":"816.032295ms","remote":"127.0.0.1:33700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-04-26T00:42:03.709645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:03.030023Z","time spent":"679.556814ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T00:42:03.714094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.781351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T00:42:03.714111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.173456ms","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-26T00:42:03.71422Z","caller":"traceutil/trace.go:171","msg":"trace[177535623] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:765; }","duration":"476.3302ms","start":"2026-04-26T00:42:03.237875Z","end":"2026-04-26T00:42:03.714205Z","steps":["trace[177535623] 'agreement among raft nodes before linearized reading' (duration: 476.155326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:03.714249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:03.237852Z","time spent":"476.389351ms","remote":"127.0.0.1:33994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-26T00:42:03.714157Z","caller":"traceutil/trace.go:171","msg":"trace[1195655112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"258.877243ms","start":"2026-04-26T00:42:03.455263Z","end":"2026-04-26T00:42:03.71414Z","steps":["trace[1195655112] 'agreement among raft nodes before linearized reading' (duration: 258.74728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:03.714749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.339701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-wdxf8\" ","response":"range_response_count:1 size:5047"} {"level":"info","ts":"2026-04-26T00:42:03.714799Z","caller":"traceutil/trace.go:171","msg":"trace[1998379479] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-wdxf8; range_end:; response_count:1; response_revision:765; }","duration":"703.435323ms","start":"2026-04-26T00:42:03.011352Z","end":"2026-04-26T00:42:03.714787Z","steps":["trace[1998379479] 'agreement among raft nodes before linearized reading' (duration: 702.580615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:03.714835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:03.011325Z","time spent":"703.503825ms","remote":"127.0.0.1:33700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5069,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-wdxf8\" "} {"level":"info","ts":"2026-04-26T00:42:08.282542Z","caller":"traceutil/trace.go:171","msg":"trace[379484340] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"236.398145ms","start":"2026-04-26T00:42:08.046117Z","end":"2026-04-26T00:42:08.282515Z","steps":["trace[379484340] 'process raft request' (duration: 236.230062ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:08.283285Z","caller":"traceutil/trace.go:171","msg":"trace[1845885848] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:845; }","duration":"223.964642ms","start":"2026-04-26T00:42:08.059304Z","end":"2026-04-26T00:42:08.283269Z","steps":["trace[1845885848] 'read index received' (duration: 223.958782ms)","trace[1845885848] 'applied index is now lower than readState.Index' (duration: 4.7µs)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:42:08.283505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.178307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-26T00:42:08.283551Z","caller":"traceutil/trace.go:171","msg":"trace[1231572933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:821; }","duration":"224.251898ms","start":"2026-04-26T00:42:08.059285Z","end":"2026-04-26T00:42:08.283537Z","steps":["trace[1231572933] 'agreement among raft nodes before linearized reading' (duration: 224.079884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:08.283659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.40611ms","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-26T00:42:08.283732Z","caller":"traceutil/trace.go:171","msg":"trace[230394113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:821; }","duration":"178.523532ms","start":"2026-04-26T00:42:08.105191Z","end":"2026-04-26T00:42:08.283714Z","steps":["trace[230394113] 'agreement among raft nodes before linearized reading' (duration: 178.241247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:08.283921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.991416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-26T00:42:08.283947Z","caller":"traceutil/trace.go:171","msg":"trace[687319924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:821; }","duration":"170.049066ms","start":"2026-04-26T00:42:08.11389Z","end":"2026-04-26T00:42:08.283939Z","steps":["trace[687319924] 'agreement among raft nodes before linearized reading' (duration: 169.964885ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:08.433008Z","caller":"traceutil/trace.go:171","msg":"trace[1098609156] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"144.079259ms","start":"2026-04-26T00:42:08.288914Z","end":"2026-04-26T00:42:08.432993Z","steps":["trace[1098609156] 'process raft request' (duration: 143.768723ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:08.433243Z","caller":"traceutil/trace.go:171","msg":"trace[448465493] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"143.504869ms","start":"2026-04-26T00:42:08.289729Z","end":"2026-04-26T00:42:08.433234Z","steps":["trace[448465493] 'process raft request' (duration: 143.06691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:09.041197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.695261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-26T00:42:09.041645Z","caller":"traceutil/trace.go:171","msg":"trace[1195807799] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:849; }","duration":"229.207458ms","start":"2026-04-26T00:42:08.812413Z","end":"2026-04-26T00:42:09.041621Z","steps":["trace[1195807799] 'read index received' (duration: 76.913416ms)","trace[1195807799] 'applied index is now lower than readState.Index' (duration: 152.289352ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:42:09.041803Z","caller":"traceutil/trace.go:171","msg":"trace[269200062] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"237.512491ms","start":"2026-04-26T00:42:08.804279Z","end":"2026-04-26T00:42:09.041792Z","steps":["trace[269200062] 'process raft request' (duration: 85.072595ms)","trace[269200062] 'compare' (duration: 151.424556ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:42:09.041833Z","caller":"traceutil/trace.go:171","msg":"trace[740619019] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"233.460633ms","start":"2026-04-26T00:42:08.808347Z","end":"2026-04-26T00:42:09.041808Z","steps":["trace[740619019] 'process raft request' (duration: 233.127605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:09.042269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.845371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-oidc-hmac\" ","response":"range_response_count:1 size:452"} {"level":"info","ts":"2026-04-26T00:42:09.042352Z","caller":"traceutil/trace.go:171","msg":"trace[603643668] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-oidc-hmac; range_end:; response_count:1; response_revision:826; }","duration":"229.964794ms","start":"2026-04-26T00:42:08.812375Z","end":"2026-04-26T00:42:09.04234Z","steps":["trace[603643668] 'agreement among raft nodes before linearized reading' (duration: 229.717169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:09.042514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.495449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:42:09.042746Z","caller":"traceutil/trace.go:171","msg":"trace[108474156] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:0; response_revision:826; }","duration":"125.636842ms","start":"2026-04-26T00:42:08.916943Z","end":"2026-04-26T00:42:09.04258Z","steps":["trace[108474156] 'agreement among raft nodes before linearized reading' (duration: 125.363387ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:10.549547Z","caller":"traceutil/trace.go:171","msg":"trace[142357440] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:860; }","duration":"163.923083ms","start":"2026-04-26T00:42:10.385595Z","end":"2026-04-26T00:42:10.549518Z","steps":["trace[142357440] 'read index received' (duration: 163.571617ms)","trace[142357440] 'applied index is now lower than readState.Index' (duration: 350.316µs)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:42:10.54981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.127786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-26T00:42:10.54986Z","caller":"traceutil/trace.go:171","msg":"trace[1543600025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:836; }","duration":"113.245519ms","start":"2026-04-26T00:42:10.436597Z","end":"2026-04-26T00:42:10.549842Z","steps":["trace[1543600025] 'agreement among raft nodes before linearized reading' (duration: 113.061476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:10.549889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.2745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-26T00:42:10.549904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.966964ms","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-26T00:42:10.549973Z","caller":"traceutil/trace.go:171","msg":"trace[887313607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:836; }","duration":"164.444944ms","start":"2026-04-26T00:42:10.385508Z","end":"2026-04-26T00:42:10.549953Z","steps":["trace[887313607] 'agreement among raft nodes before linearized reading' (duration: 164.152719ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:10.54998Z","caller":"traceutil/trace.go:171","msg":"trace[128146767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:836; }","duration":"112.092416ms","start":"2026-04-26T00:42:10.437874Z","end":"2026-04-26T00:42:10.549967Z","steps":["trace[128146767] 'agreement among raft nodes before linearized reading' (duration: 111.824542ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:10.54964Z","caller":"traceutil/trace.go:171","msg":"trace[496388633] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"259.578453ms","start":"2026-04-26T00:42:10.290015Z","end":"2026-04-26T00:42:10.549594Z","steps":["trace[496388633] 'process raft request' (duration: 259.269627ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:11.09463Z","caller":"traceutil/trace.go:171","msg":"trace[1287489842] linearizableReadLoop","detail":"{readStateIndex:864; appliedIndex:862; }","duration":"186.408031ms","start":"2026-04-26T00:42:10.908203Z","end":"2026-04-26T00:42:11.094611Z","steps":["trace[1287489842] 'read index received' (duration: 150.506066ms)","trace[1287489842] 'applied index is now lower than readState.Index' (duration: 35.901125ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:42:11.094812Z","caller":"traceutil/trace.go:171","msg":"trace[378871387] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"540.37191ms","start":"2026-04-26T00:42:10.55443Z","end":"2026-04-26T00:42:11.094802Z","steps":["trace[378871387] 'process raft request' (duration: 540.141715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:11.094939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:10.554405Z","time spent":"540.468812ms","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T00:42:11.094691Z","caller":"traceutil/trace.go:171","msg":"trace[854935489] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"540.247917ms","start":"2026-04-26T00:42:10.554418Z","end":"2026-04-26T00:42:11.094666Z","steps":["trace[854935489] 'process raft request' (duration: 504.303131ms)","trace[854935489] 'compare' (duration: 35.756653ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:42:11.095181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.92942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4543"} {"level":"warn","ts":"2026-04-26T00:42:11.095205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.734748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:42:11.095254Z","caller":"traceutil/trace.go:171","msg":"trace[1310467003] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:839; }","duration":"187.087394ms","start":"2026-04-26T00:42:10.908152Z","end":"2026-04-26T00:42:11.095239Z","steps":["trace[1310467003] 'agreement among raft nodes before linearized reading' (duration: 186.847279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:11.095276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:10.554407Z","time spent":"540.802517ms","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T00:42:11.095254Z","caller":"traceutil/trace.go:171","msg":"trace[2049041654] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:839; }","duration":"120.806619ms","start":"2026-04-26T00:42:10.974427Z","end":"2026-04-26T00:42:11.095233Z","steps":["trace[2049041654] 'agreement among raft nodes before linearized reading' (duration: 120.746988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:11.095118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.858885ms","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-26T00:42:11.095817Z","caller":"traceutil/trace.go:171","msg":"trace[1004651434] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:839; }","duration":"152.56151ms","start":"2026-04-26T00:42:10.943204Z","end":"2026-04-26T00:42:11.095766Z","steps":["trace[1004651434] 'agreement among raft nodes before linearized reading' (duration: 151.820236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:11.66768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.200225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T00:42:11.667878Z","caller":"traceutil/trace.go:171","msg":"trace[1323500403] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"512.79062ms","start":"2026-04-26T00:42:11.155062Z","end":"2026-04-26T00:42:11.667852Z","steps":["trace[1323500403] 'process raft request' (duration: 297.328801ms)","trace[1323500403] 'compare' (duration: 215.072352ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:42:11.668002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:11.155041Z","time spent":"512.887402ms","remote":"127.0.0.1:33700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3856,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T00:42:11.668302Z","caller":"traceutil/trace.go:171","msg":"trace[2114754449] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"512.512895ms","start":"2026-04-26T00:42:11.155761Z","end":"2026-04-26T00:42:11.668274Z","steps":["trace[2114754449] 'process raft request' (duration: 512.021106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:11.668452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:11.155751Z","time spent":"512.624409ms","remote":"127.0.0.1:51468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2318,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T00:42:11.669743Z","caller":"traceutil/trace.go:171","msg":"trace[1051091313] linearizableReadLoop","detail":"{readStateIndex:870; appliedIndex:866; }","duration":"448.456933ms","start":"2026-04-26T00:42:11.221267Z","end":"2026-04-26T00:42:11.669724Z","steps":["trace[1051091313] 'read index received' (duration: 231.137459ms)","trace[1051091313] 'applied index is now lower than readState.Index' (duration: 217.318574ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:42:11.670321Z","caller":"traceutil/trace.go:171","msg":"trace[1717948282] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"458.72991ms","start":"2026-04-26T00:42:11.211579Z","end":"2026-04-26T00:42:11.670309Z","steps":["trace[1717948282] 'process raft request' (duration: 456.263703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:11.670397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:11.211555Z","time spent":"458.799161ms","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T00:42:11.670829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.421687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:42:11.670848Z","caller":"traceutil/trace.go:171","msg":"trace[273286454] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"449.671296ms","start":"2026-04-26T00:42:11.221145Z","end":"2026-04-26T00:42:11.670817Z","steps":["trace[273286454] 'process raft request' (duration: 448.529484ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:11.670905Z","caller":"traceutil/trace.go:171","msg":"trace[899827706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:845; }","duration":"216.5485ms","start":"2026-04-26T00:42:11.45434Z","end":"2026-04-26T00:42:11.670888Z","steps":["trace[899827706] 'agreement among raft nodes before linearized reading' (duration: 216.383697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:11.670906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.629506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-5l6jq\" ","response":"range_response_count:1 size:3871"} {"level":"info","ts":"2026-04-26T00:42:11.670946Z","caller":"traceutil/trace.go:171","msg":"trace[1356321495] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-5l6jq; range_end:; response_count:1; response_revision:845; }","duration":"449.693047ms","start":"2026-04-26T00:42:11.221243Z","end":"2026-04-26T00:42:11.670936Z","steps":["trace[1356321495] 'agreement among raft nodes before linearized reading' (duration: 449.541644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:11.670938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:11.221114Z","time spent":"449.786549ms","remote":"127.0.0.1:33596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":719,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-26T00:42:11.670965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:11.221235Z","time spent":"449.726857ms","remote":"127.0.0.1:33700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":3893,"request content":"key:\"/registry/pods/kube-system/coredns-67659f764b-5l6jq\" "} {"level":"warn","ts":"2026-04-26T00:42:11.671014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.743194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-04-26T00:42:11.671087Z","caller":"traceutil/trace.go:171","msg":"trace[608544039] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:845; }","duration":"171.866107ms","start":"2026-04-26T00:42:11.499204Z","end":"2026-04-26T00:42:11.67107Z","steps":["trace[608544039] 'agreement among raft nodes before linearized reading' (duration: 171.694544ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:11.908121Z","caller":"traceutil/trace.go:171","msg":"trace[1220881880] linearizableReadLoop","detail":"{readStateIndex:872; appliedIndex:871; }","duration":"144.67193ms","start":"2026-04-26T00:42:11.763424Z","end":"2026-04-26T00:42:11.908096Z","steps":["trace[1220881880] 'read index received' (duration: 117.747956ms)","trace[1220881880] 'applied index is now lower than readState.Index' (duration: 26.923024ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:42:11.908215Z","caller":"traceutil/trace.go:171","msg":"trace[524570434] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"227.841107ms","start":"2026-04-26T00:42:11.680347Z","end":"2026-04-26T00:42:11.908188Z","steps":["trace[524570434] 'process raft request' (duration: 200.828521ms)","trace[524570434] 'compare' (duration: 26.794292ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:42:11.908388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.940635ms","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-26T00:42:11.908429Z","caller":"traceutil/trace.go:171","msg":"trace[257024598] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:847; }","duration":"145.029717ms","start":"2026-04-26T00:42:11.763388Z","end":"2026-04-26T00:42:11.908418Z","steps":["trace[257024598] 'agreement among raft nodes before linearized reading' (duration: 144.835823ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:13.313219Z","caller":"traceutil/trace.go:171","msg":"trace[1980075632] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"167.89581ms","start":"2026-04-26T00:42:13.145301Z","end":"2026-04-26T00:42:13.313196Z","steps":["trace[1980075632] 'process raft request' (duration: 167.805628ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:13.313264Z","caller":"traceutil/trace.go:171","msg":"trace[1485005875] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"168.829876ms","start":"2026-04-26T00:42:13.144408Z","end":"2026-04-26T00:42:13.313238Z","steps":["trace[1485005875] 'process raft request' (duration: 124.052106ms)","trace[1485005875] 'compare' (duration: 44.339262ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:42:26.730884Z","caller":"traceutil/trace.go:171","msg":"trace[138287234] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"105.612938ms","start":"2026-04-26T00:42:26.625235Z","end":"2026-04-26T00:42:26.730848Z","steps":["trace[138287234] 'process raft request' (duration: 105.522766ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:39.433146Z","caller":"traceutil/trace.go:171","msg":"trace[1042583783] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"138.659391ms","start":"2026-04-26T00:42:39.294467Z","end":"2026-04-26T00:42:39.433126Z","steps":["trace[1042583783] 'process raft request' (duration: 138.499129ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:39.489265Z","caller":"traceutil/trace.go:171","msg":"trace[1456819600] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"191.62407ms","start":"2026-04-26T00:42:39.297621Z","end":"2026-04-26T00:42:39.489245Z","steps":["trace[1456819600] 'process raft request' (duration: 191.454557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:40.204301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.653626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-26T00:42:40.204467Z","caller":"traceutil/trace.go:171","msg":"trace[2063363228] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1064; }","duration":"105.140924ms","start":"2026-04-26T00:42:40.099304Z","end":"2026-04-26T00:42:40.204444Z","steps":["trace[2063363228] 'read index received' (duration: 52.871µs)","trace[2063363228] 'applied index is now lower than readState.Index' (duration: 105.085313ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:42:40.204693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.388398ms","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-26T00:42:40.204745Z","caller":"traceutil/trace.go:171","msg":"trace[1492445821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1033; }","duration":"105.486438ms","start":"2026-04-26T00:42:40.099245Z","end":"2026-04-26T00:42:40.204732Z","steps":["trace[1492445821] 'agreement among raft nodes before linearized reading' (duration: 105.266865ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:40.204828Z","caller":"traceutil/trace.go:171","msg":"trace[1267026840] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"258.523102ms","start":"2026-04-26T00:42:39.946271Z","end":"2026-04-26T00:42:40.204795Z","steps":["trace[1267026840] 'process raft request' (duration: 104.233116ms)","trace[1267026840] 'compare' (duration: 153.131738ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:42:40.811765Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291073999181421,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-26T00:42:40.981045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.136559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T00:42:40.981367Z","caller":"traceutil/trace.go:171","msg":"trace[941984799] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"670.364133ms","start":"2026-04-26T00:42:40.310985Z","end":"2026-04-26T00:42:40.981349Z","steps":["trace[941984799] 'read index received' (duration: 563.647536ms)","trace[941984799] 'applied index is now lower than readState.Index' (duration: 106.715277ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:42:40.981526Z","caller":"traceutil/trace.go:171","msg":"trace[1579054664] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"772.752053ms","start":"2026-04-26T00:42:40.208723Z","end":"2026-04-26T00:42:40.981475Z","steps":["trace[1579054664] 'process raft request' (duration: 665.926695ms)","trace[1579054664] 'compare' (duration: 105.958585ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:42:40.981703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.568437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:42:40.981779Z","caller":"traceutil/trace.go:171","msg":"trace[1493096334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1034; }","duration":"526.717961ms","start":"2026-04-26T00:42:40.455024Z","end":"2026-04-26T00:42:40.981742Z","steps":["trace[1493096334] 'agreement among raft nodes before linearized reading' (duration: 526.580979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:40.981817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:40.455003Z","time spent":"526.804642ms","remote":"127.0.0.1:33490","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-26T00:42:40.981838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.409185ms","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-26T00:42:40.98194Z","caller":"traceutil/trace.go:171","msg":"trace[446515330] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1034; }","duration":"215.586568ms","start":"2026-04-26T00:42:40.766332Z","end":"2026-04-26T00:42:40.981919Z","steps":["trace[446515330] 'agreement among raft nodes before linearized reading' (duration: 215.373355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:40.982028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.482609ms","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-26T00:42:40.982057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.661419ms","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-26T00:42:40.982109Z","caller":"traceutil/trace.go:171","msg":"trace[1726423784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1034; }","duration":"195.602231ms","start":"2026-04-26T00:42:40.786488Z","end":"2026-04-26T00:42:40.982091Z","steps":["trace[1726423784] 'agreement among raft nodes before linearized reading' (duration: 195.385788ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:40.982126Z","caller":"traceutil/trace.go:171","msg":"trace[776229147] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1034; }","duration":"249.774721ms","start":"2026-04-26T00:42:40.732333Z","end":"2026-04-26T00:42:40.982108Z","steps":["trace[776229147] 'agreement among raft nodes before linearized reading' (duration: 249.556238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:40.982041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:40.2087Z","time spent":"772.964568ms","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T00:42:40.982112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.360142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-23ef042b-1ee4-46ce-b7e6-c05d049315cb\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-04-26T00:42:40.98229Z","caller":"traceutil/trace.go:171","msg":"trace[410760600] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-23ef042b-1ee4-46ce-b7e6-c05d049315cb; range_end:; response_count:1; response_revision:1034; }","duration":"488.579305ms","start":"2026-04-26T00:42:40.493699Z","end":"2026-04-26T00:42:40.982278Z","steps":["trace[410760600] 'agreement among raft nodes before linearized reading' (duration: 488.312671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:40.981978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.950843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-26T00:42:40.982335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:40.49368Z","time spent":"488.644316ms","remote":"127.0.0.1:33700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3601,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-23ef042b-1ee4-46ce-b7e6-c05d049315cb\" "} {"level":"info","ts":"2026-04-26T00:42:40.982485Z","caller":"traceutil/trace.go:171","msg":"trace[1502077596] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1034; }","duration":"671.446339ms","start":"2026-04-26T00:42:40.31093Z","end":"2026-04-26T00:42:40.982376Z","steps":["trace[1502077596] 'agreement among raft nodes before linearized reading' (duration: 670.904651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:40.982721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:40.310903Z","time spent":"671.758794ms","remote":"127.0.0.1:33650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":6,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-04-26T00:42:41.660216Z","caller":"traceutil/trace.go:171","msg":"trace[1656605653] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"140.801825ms","start":"2026-04-26T00:42:41.519386Z","end":"2026-04-26T00:42:41.660188Z","steps":["trace[1656605653] 'process raft request' (duration: 120.917802ms)","trace[1656605653] 'compare' (duration: 19.770461ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:42:45.290271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.078339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T00:42:45.290602Z","caller":"traceutil/trace.go:171","msg":"trace[1586114134] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1084; }","duration":"210.10059ms","start":"2026-04-26T00:42:45.080483Z","end":"2026-04-26T00:42:45.290584Z","steps":["trace[1586114134] 'read index received' (duration: 14.557635ms)","trace[1586114134] 'applied index is now lower than readState.Index' (duration: 195.541495ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:42:45.290757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.269993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-26T00:42:45.290699Z","caller":"traceutil/trace.go:171","msg":"trace[658165101] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"238.442941ms","start":"2026-04-26T00:42:45.052187Z","end":"2026-04-26T00:42:45.29063Z","steps":["trace[658165101] 'process raft request' (duration: 54.924955ms)","trace[658165101] 'compare' (duration: 182.962608ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:42:45.290796Z","caller":"traceutil/trace.go:171","msg":"trace[2144533913] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1053; }","duration":"210.362074ms","start":"2026-04-26T00:42:45.080425Z","end":"2026-04-26T00:42:45.290787Z","steps":["trace[2144533913] 'agreement among raft nodes before linearized reading' (duration: 210.212052ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:45.500021Z","caller":"traceutil/trace.go:171","msg":"trace[882682915] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"120.224526ms","start":"2026-04-26T00:42:45.379765Z","end":"2026-04-26T00:42:45.499989Z","steps":["trace[882682915] 'process raft request' (duration: 22.723397ms)","trace[882682915] 'compare' (duration: 97.368717ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:42:45.500287Z","caller":"traceutil/trace.go:171","msg":"trace[1302787357] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"112.625043ms","start":"2026-04-26T00:42:45.387633Z","end":"2026-04-26T00:42:45.500258Z","steps":["trace[1302787357] 'process raft request' (duration: 112.425051ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:48.724654Z","caller":"traceutil/trace.go:171","msg":"trace[1274150919] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"290.527296ms","start":"2026-04-26T00:42:48.434102Z","end":"2026-04-26T00:42:48.724629Z","steps":["trace[1274150919] 'read index received' (duration: 290.394944ms)","trace[1274150919] 'applied index is now lower than readState.Index' (duration: 131.412µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:42:48.724702Z","caller":"traceutil/trace.go:171","msg":"trace[73358572] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"381.078485ms","start":"2026-04-26T00:42:48.343591Z","end":"2026-04-26T00:42:48.724669Z","steps":["trace[73358572] 'process raft request' (duration: 380.890692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:48.724825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.229376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-26T00:42:48.724864Z","caller":"traceutil/trace.go:171","msg":"trace[2003451525] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1079; }","duration":"195.324428ms","start":"2026-04-26T00:42:48.529531Z","end":"2026-04-26T00:42:48.724855Z","steps":["trace[2003451525] 'agreement among raft nodes before linearized reading' (duration: 195.238057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:48.724867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:48.343564Z","time spent":"381.210067ms","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T00:42:48.725005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.868951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:42:48.725151Z","caller":"traceutil/trace.go:171","msg":"trace[59985296] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1079; }","duration":"291.024322ms","start":"2026-04-26T00:42:48.434055Z","end":"2026-04-26T00:42:48.725079Z","steps":["trace[59985296] 'agreement among raft nodes before linearized reading' (duration: 290.685128ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:52.227547Z","caller":"traceutil/trace.go:171","msg":"trace[165292094] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"489.532589ms","start":"2026-04-26T00:42:51.737985Z","end":"2026-04-26T00:42:52.227518Z","steps":["trace[165292094] 'process raft request' (duration: 489.382457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:52.227709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:51.73797Z","time spent":"489.65535ms","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T00:42:52.470371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.125417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T00:42:52.470483Z","caller":"traceutil/trace.go:171","msg":"trace[1941406216] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1124; }","duration":"107.209804ms","start":"2026-04-26T00:42:52.36325Z","end":"2026-04-26T00:42:52.47046Z","steps":["trace[1941406216] 'read index received' (duration: 31.5µs)","trace[1941406216] 'applied index is now lower than readState.Index' (duration: 107.177264ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:42:52.470486Z","caller":"traceutil/trace.go:171","msg":"trace[401267646] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"487.079433ms","start":"2026-04-26T00:42:51.983385Z","end":"2026-04-26T00:42:52.470465Z","steps":["trace[401267646] 'process raft request' (duration: 369.787603ms)","trace[401267646] 'compare' (duration: 117.002846ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:42:52.470579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:51.983363Z","time spent":"487.163253ms","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T00:42:52.470596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.333786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-26T00:42:52.470644Z","caller":"traceutil/trace.go:171","msg":"trace[847084728] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1091; }","duration":"107.412618ms","start":"2026-04-26T00:42:52.363218Z","end":"2026-04-26T00:42:52.470631Z","steps":["trace[847084728] 'agreement among raft nodes before linearized reading' (duration: 107.336447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:58.982674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.188106ms","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-26T00:42:58.982847Z","caller":"traceutil/trace.go:171","msg":"trace[246246598] 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:1116; }","duration":"497.431229ms","start":"2026-04-26T00:42:58.485392Z","end":"2026-04-26T00:42:58.982823Z","steps":["trace[246246598] 'count revisions from in-memory index tree' (duration: 497.059854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:58.98294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:58.485372Z","time spent":"497.54213ms","remote":"127.0.0.1:51334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-26T00:42:58.982976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.763435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T00:42:58.983295Z","caller":"traceutil/trace.go:171","msg":"trace[2110955872] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"496.428225ms","start":"2026-04-26T00:42:58.486848Z","end":"2026-04-26T00:42:58.983276Z","steps":["trace[2110955872] 'process raft request' (duration: 360.274124ms)","trace[2110955872] 'compare' (duration: 135.636134ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:42:58.983356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:58.486821Z","time spent":"496.511156ms","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-26T00:42:58.983353Z","caller":"traceutil/trace.go:171","msg":"trace[1407806697] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"151.722496ms","start":"2026-04-26T00:42:58.831612Z","end":"2026-04-26T00:42:58.983334Z","steps":["trace[1407806697] 'process raft request' (duration: 151.640675ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:58.983531Z","caller":"traceutil/trace.go:171","msg":"trace[1597192484] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"209.727584ms","start":"2026-04-26T00:42:58.773683Z","end":"2026-04-26T00:42:58.98341Z","steps":["trace[1597192484] 'read index received' (duration: 73.462211ms)","trace[1597192484] 'applied index is now lower than readState.Index' (duration: 136.263743ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:42:58.983604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.920136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:42:58.983639Z","caller":"traceutil/trace.go:171","msg":"trace[227487331] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1118; }","duration":"209.998637ms","start":"2026-04-26T00:42:58.773635Z","end":"2026-04-26T00:42:58.983633Z","steps":["trace[227487331] 'agreement among raft nodes before linearized reading' (duration: 209.930266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:59.650442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.406051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:42:59.650522Z","caller":"traceutil/trace.go:171","msg":"trace[1560236723] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1118; }","duration":"549.540673ms","start":"2026-04-26T00:42:59.100964Z","end":"2026-04-26T00:42:59.650504Z","steps":["trace[1560236723] 'range keys from in-memory index tree' (duration: 549.31243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:59.65056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:59.100938Z","time spent":"549.613374ms","remote":"127.0.0.1:33660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-26T00:42:59.650594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.111683ms","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-26T00:42:59.650669Z","caller":"traceutil/trace.go:171","msg":"trace[163478092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1118; }","duration":"213.223085ms","start":"2026-04-26T00:42:59.437429Z","end":"2026-04-26T00:42:59.650652Z","steps":["trace[163478092] 'range keys from in-memory index tree' (duration: 212.949392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:59.650786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.567841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:42:59.650815Z","caller":"traceutil/trace.go:171","msg":"trace[321956258] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1118; }","duration":"363.628582ms","start":"2026-04-26T00:42:59.287178Z","end":"2026-04-26T00:42:59.650807Z","steps":["trace[321956258] 'count revisions from in-memory index tree' (duration: 363.50193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:59.650843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:59.287161Z","time spent":"363.675153ms","remote":"127.0.0.1:33928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-04-26T00:42:59.650951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.779746ms","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-26T00:42:59.651018Z","caller":"traceutil/trace.go:171","msg":"trace[1423954412] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1118; }","duration":"429.877128ms","start":"2026-04-26T00:42:59.221126Z","end":"2026-04-26T00:42:59.651004Z","steps":["trace[1423954412] 'range keys from in-memory index tree' (duration: 429.652915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:59.651068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:59.221107Z","time spent":"429.950859ms","remote":"127.0.0.1:33780","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":"warn","ts":"2026-04-26T00:42:59.651087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.622515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:42:59.651153Z","caller":"traceutil/trace.go:171","msg":"trace[1512169212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1118; }","duration":"195.721257ms","start":"2026-04-26T00:42:59.455416Z","end":"2026-04-26T00:42:59.651137Z","steps":["trace[1512169212] 'range keys from in-memory index tree' (duration: 195.546444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:59.651458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.032653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-26T00:42:59.651488Z","caller":"traceutil/trace.go:171","msg":"trace[451351878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1118; }","duration":"409.100864ms","start":"2026-04-26T00:42:59.242377Z","end":"2026-04-26T00:42:59.651478Z","steps":["trace[451351878] 'range keys from in-memory index tree' (duration: 408.715779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:59.65152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:42:59.242352Z","time spent":"409.160754ms","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-26T00:42:59.781294Z","caller":"traceutil/trace.go:171","msg":"trace[1110821577] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"126.786954ms","start":"2026-04-26T00:42:59.654487Z","end":"2026-04-26T00:42:59.781274Z","steps":["trace[1110821577] 'process raft request' (duration: 126.677262ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:59.805006Z","caller":"traceutil/trace.go:171","msg":"trace[982803063] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"149.275071ms","start":"2026-04-26T00:42:59.655707Z","end":"2026-04-26T00:42:59.804983Z","steps":["trace[982803063] 'process raft request' (duration: 149.092589ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:42:59.805125Z","caller":"traceutil/trace.go:171","msg":"trace[1580125823] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1155; }","duration":"148.986178ms","start":"2026-04-26T00:42:59.656113Z","end":"2026-04-26T00:42:59.805099Z","steps":["trace[1580125823] 'read index received' (duration: 125.162722ms)","trace[1580125823] 'applied index is now lower than readState.Index' (duration: 23.821716ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:42:59.805173Z","caller":"traceutil/trace.go:171","msg":"trace[28132038] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"149.103499ms","start":"2026-04-26T00:42:59.65605Z","end":"2026-04-26T00:42:59.805154Z","steps":["trace[28132038] 'process raft request' (duration: 148.898597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:59.805276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.1324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:42:59.805329Z","caller":"traceutil/trace.go:171","msg":"trace[404407649] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1121; }","duration":"149.263652ms","start":"2026-04-26T00:42:59.656052Z","end":"2026-04-26T00:42:59.805315Z","steps":["trace[404407649] 'agreement among raft nodes before linearized reading' (duration: 149.15621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:42:59.805338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.5991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-26T00:42:59.805369Z","caller":"traceutil/trace.go:171","msg":"trace[1803810141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1121; }","duration":"101.674071ms","start":"2026-04-26T00:42:59.703683Z","end":"2026-04-26T00:42:59.805357Z","steps":["trace[1803810141] 'agreement among raft nodes before linearized reading' (duration: 101.546709ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:43:00.38155Z","caller":"traceutil/trace.go:171","msg":"trace[364308138] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"101.908574ms","start":"2026-04-26T00:43:00.279622Z","end":"2026-04-26T00:43:00.381531Z","steps":["trace[364308138] 'process raft request' (duration: 101.790123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:01.973402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.483994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-26T00:43:01.973483Z","caller":"traceutil/trace.go:171","msg":"trace[2093410584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1131; }","duration":"130.611986ms","start":"2026-04-26T00:43:01.84285Z","end":"2026-04-26T00:43:01.973462Z","steps":["trace[2093410584] 'range keys from in-memory index tree' (duration: 130.304402ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:43:12.879504Z","caller":"traceutil/trace.go:171","msg":"trace[1156880040] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1203; }","duration":"426.410114ms","start":"2026-04-26T00:43:12.453074Z","end":"2026-04-26T00:43:12.879484Z","steps":["trace[1156880040] 'read index received' (duration: 426.227712ms)","trace[1156880040] 'applied index is now lower than readState.Index' (duration: 181.652µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:43:12.879553Z","caller":"traceutil/trace.go:171","msg":"trace[1018814151] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"432.526371ms","start":"2026-04-26T00:43:12.446998Z","end":"2026-04-26T00:43:12.879524Z","steps":["trace[1018814151] 'process raft request' (duration: 432.37169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:12.879627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.540695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:43:12.879654Z","caller":"traceutil/trace.go:171","msg":"trace[666655497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1166; }","duration":"426.611256ms","start":"2026-04-26T00:43:12.453035Z","end":"2026-04-26T00:43:12.879646Z","steps":["trace[666655497] 'agreement among raft nodes before linearized reading' (duration: 426.553545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:12.87968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:43:12.453018Z","time spent":"426.657656ms","remote":"127.0.0.1:33490","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-26T00:43:12.879687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:43:12.446966Z","time spent":"432.650463ms","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T00:43:12.879789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.856516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:43:12.879849Z","caller":"traceutil/trace.go:171","msg":"trace[155105878] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1166; }","duration":"357.944058ms","start":"2026-04-26T00:43:12.521882Z","end":"2026-04-26T00:43:12.879826Z","steps":["trace[155105878] 'agreement among raft nodes before linearized reading' (duration: 357.851267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:12.879888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:43:12.52187Z","time spent":"358.007639ms","remote":"127.0.0.1:51238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-26T00:43:12.879823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.676532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-proxier\" ","response":"range_response_count:1 size:712"} {"level":"info","ts":"2026-04-26T00:43:12.879999Z","caller":"traceutil/trace.go:171","msg":"trace[225497705] range","detail":"{range_begin:/registry/clusterroles/system:node-proxier; range_end:; response_count:1; response_revision:1166; }","duration":"421.860024ms","start":"2026-04-26T00:43:12.458118Z","end":"2026-04-26T00:43:12.879978Z","steps":["trace[225497705] 'agreement among raft nodes before linearized reading' (duration: 421.589521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:12.880045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:43:12.458113Z","time spent":"421.919505ms","remote":"127.0.0.1:33880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":734,"request content":"key:\"/registry/clusterroles/system:node-proxier\" "} {"level":"warn","ts":"2026-04-26T00:43:12.880395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.943038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ephemeral-volume-controller\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-04-26T00:43:12.880569Z","caller":"traceutil/trace.go:171","msg":"trace[1382444552] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ephemeral-volume-controller; range_end:; response_count:1; response_revision:1166; }","duration":"394.14776ms","start":"2026-04-26T00:43:12.486399Z","end":"2026-04-26T00:43:12.880546Z","steps":["trace[1382444552] 'agreement among raft nodes before linearized reading' (duration: 393.36586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:12.880615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:43:12.486378Z","time spent":"394.225811ms","remote":"127.0.0.1:33882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":805,"request content":"key:\"/registry/clusterrolebindings/system:controller:ephemeral-volume-controller\" "} {"level":"info","ts":"2026-04-26T00:43:14.049393Z","caller":"traceutil/trace.go:171","msg":"trace[470590638] linearizableReadLoop","detail":"{readStateIndex:1207; appliedIndex:1206; }","duration":"144.76666ms","start":"2026-04-26T00:43:13.904606Z","end":"2026-04-26T00:43:14.049373Z","steps":["trace[470590638] 'read index received' (duration: 144.595138ms)","trace[470590638] 'applied index is now lower than readState.Index' (duration: 170.542µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:43:14.049442Z","caller":"traceutil/trace.go:171","msg":"trace[386206096] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"164.648993ms","start":"2026-04-26T00:43:13.884763Z","end":"2026-04-26T00:43:14.049412Z","steps":["trace[386206096] 'process raft request' (duration: 164.489361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:14.049546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.927832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:persistent-volume-binder\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-04-26T00:43:14.049987Z","caller":"traceutil/trace.go:171","msg":"trace[1988435930] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:persistent-volume-binder; range_end:; response_count:1; response_revision:1168; }","duration":"145.380498ms","start":"2026-04-26T00:43:13.904577Z","end":"2026-04-26T00:43:14.049958Z","steps":["trace[1988435930] 'agreement among raft nodes before linearized reading' (duration: 144.905602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:14.050026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.361277ms","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-26T00:43:14.050062Z","caller":"traceutil/trace.go:171","msg":"trace[2147177640] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1168; }","duration":"119.412618ms","start":"2026-04-26T00:43:13.930633Z","end":"2026-04-26T00:43:14.050046Z","steps":["trace[2147177640] 'agreement among raft nodes before linearized reading' (duration: 119.303337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:14.050237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.815996ms","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-26T00:43:14.050293Z","caller":"traceutil/trace.go:171","msg":"trace[886078486] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1168; }","duration":"113.909347ms","start":"2026-04-26T00:43:13.936369Z","end":"2026-04-26T00:43:14.050278Z","steps":["trace[886078486] 'agreement among raft nodes before linearized reading' (duration: 113.720665ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:43:15.121855Z","caller":"traceutil/trace.go:171","msg":"trace[325040405] linearizableReadLoop","detail":"{readStateIndex:1211; appliedIndex:1210; }","duration":"205.874611ms","start":"2026-04-26T00:43:14.915961Z","end":"2026-04-26T00:43:15.121835Z","steps":["trace[325040405] 'read index received' (duration: 205.768209ms)","trace[325040405] 'applied index is now lower than readState.Index' (duration: 105.382µs)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:43:15.121953Z","caller":"traceutil/trace.go:171","msg":"trace[379539407] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"236.116033ms","start":"2026-04-26T00:43:14.885802Z","end":"2026-04-26T00:43:15.121919Z","steps":["trace[379539407] 'process raft request' (duration: 235.88041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:15.12205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.073333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:service-controller\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-04-26T00:43:15.122194Z","caller":"traceutil/trace.go:171","msg":"trace[1950445944] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:service-controller; range_end:; response_count:1; response_revision:1172; }","duration":"206.212814ms","start":"2026-04-26T00:43:14.915921Z","end":"2026-04-26T00:43:15.122133Z","steps":["trace[1950445944] 'agreement among raft nodes before linearized reading' (duration: 206.001322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:15.433494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.631977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-26T00:43:15.433603Z","caller":"traceutil/trace.go:171","msg":"trace[351383813] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"311.670659ms","start":"2026-04-26T00:43:15.121911Z","end":"2026-04-26T00:43:15.433582Z","steps":["trace[351383813] 'read index received' (duration: 65.87873ms)","trace[351383813] 'applied index is now lower than readState.Index' (duration: 245.790119ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:43:15.43369Z","caller":"traceutil/trace.go:171","msg":"trace[1350562590] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"372.647677ms","start":"2026-04-26T00:43:15.061013Z","end":"2026-04-26T00:43:15.433661Z","steps":["trace[1350562590] 'process raft request' (duration: 126.785177ms)","trace[1350562590] 'compare' (duration: 245.396515ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:43:15.433814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.372914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:statefulset-controller\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-04-26T00:43:15.433854Z","caller":"traceutil/trace.go:171","msg":"trace[2081667985] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:statefulset-controller; range_end:; response_count:1; response_revision:1173; }","duration":"467.439575ms","start":"2026-04-26T00:43:14.966402Z","end":"2026-04-26T00:43:15.433841Z","steps":["trace[2081667985] 'agreement among raft nodes before linearized reading' (duration: 467.318084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:15.433898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:43:14.966388Z","time spent":"467.502246ms","remote":"127.0.0.1:33882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":785,"request content":"key:\"/registry/clusterrolebindings/system:controller:statefulset-controller\" "} {"level":"warn","ts":"2026-04-26T00:43:15.433938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:43:15.060989Z","time spent":"372.82442ms","remote":"127.0.0.1:33780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-26T00:43:15.434095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.130754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:service-controller\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-04-26T00:43:15.434158Z","caller":"traceutil/trace.go:171","msg":"trace[840124428] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:service-controller; range_end:; response_count:1; response_revision:1173; }","duration":"308.241816ms","start":"2026-04-26T00:43:15.125902Z","end":"2026-04-26T00:43:15.434144Z","steps":["trace[840124428] 'agreement among raft nodes before linearized reading' (duration: 308.121075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:15.43419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-26T00:43:15.125884Z","time spent":"308.299697ms","remote":"127.0.0.1:33882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":769,"request content":"key:\"/registry/clusterrolebindings/system:controller:service-controller\" "} {"level":"info","ts":"2026-04-26T00:43:20.21797Z","caller":"traceutil/trace.go:171","msg":"trace[1484743029] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"103.013031ms","start":"2026-04-26T00:43:20.114934Z","end":"2026-04-26T00:43:20.217947Z","steps":["trace[1484743029] 'process raft request' (duration: 102.435214ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:43:20.218166Z","caller":"traceutil/trace.go:171","msg":"trace[701355640] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"101.643552ms","start":"2026-04-26T00:43:20.116512Z","end":"2026-04-26T00:43:20.218155Z","steps":["trace[701355640] 'process raft request' (duration: 101.021045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:20.52476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.841051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-node-lease/\" range_end:\"/registry/services/endpoints/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:43:20.525035Z","caller":"traceutil/trace.go:171","msg":"trace[1761191367] range","detail":"{range_begin:/registry/services/endpoints/kube-node-lease/; range_end:/registry/services/endpoints/kube-node-lease0; response_count:0; response_revision:1193; }","duration":"208.149095ms","start":"2026-04-26T00:43:20.316872Z","end":"2026-04-26T00:43:20.525021Z","steps":["trace[1761191367] 'range keys from in-memory index tree' (duration: 207.759011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:20.525063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.382569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:1914"} {"level":"warn","ts":"2026-04-26T00:43:20.525092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.853588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:833"} {"level":"info","ts":"2026-04-26T00:43:20.525201Z","caller":"traceutil/trace.go:171","msg":"trace[2118359967] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1193; }","duration":"205.997979ms","start":"2026-04-26T00:43:20.319184Z","end":"2026-04-26T00:43:20.525182Z","steps":["trace[2118359967] 'range keys from in-memory index tree' (duration: 205.705636ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:43:20.525174Z","caller":"traceutil/trace.go:171","msg":"trace[1524527906] range","detail":"{range_begin:/registry/services/specs/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:1193; }","duration":"144.562292ms","start":"2026-04-26T00:43:20.380591Z","end":"2026-04-26T00:43:20.525153Z","steps":["trace[1524527906] 'range keys from in-memory index tree' (duration: 144.151367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:20.524774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.695166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-public/\" range_end:\"/registry/services/endpoints/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:43:20.525444Z","caller":"traceutil/trace.go:171","msg":"trace[2144489822] range","detail":"{range_begin:/registry/services/endpoints/kube-public/; range_end:/registry/services/endpoints/kube-public0; response_count:0; response_revision:1193; }","duration":"142.395585ms","start":"2026-04-26T00:43:20.38303Z","end":"2026-04-26T00:43:20.525426Z","steps":["trace[2144489822] 'range keys from in-memory index tree' (duration: 141.625926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:22.255156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.054259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/local-path-storage/\" range_end:\"/registry/services/endpoints/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:43:22.255214Z","caller":"traceutil/trace.go:171","msg":"trace[1526624580] range","detail":"{range_begin:/registry/services/endpoints/local-path-storage/; range_end:/registry/services/endpoints/local-path-storage0; response_count:0; response_revision:1196; }","duration":"103.147071ms","start":"2026-04-26T00:43:22.152051Z","end":"2026-04-26T00:43:22.255199Z","steps":["trace[1526624580] 'range keys from in-memory index tree' (duration: 102.985869ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:43:22.434269Z","caller":"traceutil/trace.go:171","msg":"trace[1349348469] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"175.212881ms","start":"2026-04-26T00:43:22.259031Z","end":"2026-04-26T00:43:22.434244Z","steps":["trace[1349348469] 'process raft request' (duration: 175.0815ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:43:22.551564Z","caller":"traceutil/trace.go:171","msg":"trace[947326070] linearizableReadLoop","detail":"{readStateIndex:1239; appliedIndex:1236; }","duration":"285.829702ms","start":"2026-04-26T00:43:22.265715Z","end":"2026-04-26T00:43:22.551545Z","steps":["trace[947326070] 'read index received' (duration: 168.376777ms)","trace[947326070] 'applied index is now lower than readState.Index' (duration: 117.451775ms)"],"step_count":2} {"level":"info","ts":"2026-04-26T00:43:22.551614Z","caller":"traceutil/trace.go:171","msg":"trace[1349403422] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"292.460204ms","start":"2026-04-26T00:43:22.259104Z","end":"2026-04-26T00:43:22.551564Z","steps":["trace[1349403422] 'process raft request' (duration: 292.373283ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:43:22.551641Z","caller":"traceutil/trace.go:171","msg":"trace[1442468356] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"292.530244ms","start":"2026-04-26T00:43:22.259078Z","end":"2026-04-26T00:43:22.551608Z","steps":["trace[1442468356] 'process raft request' (duration: 257.186122ms)","trace[1442468356] 'compare' (duration: 34.917596ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:43:22.551756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.025795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-node-lease/default\" ","response":"range_response_count:1 size:187"} {"level":"info","ts":"2026-04-26T00:43:22.551794Z","caller":"traceutil/trace.go:171","msg":"trace[1025998316] range","detail":"{range_begin:/registry/serviceaccounts/kube-node-lease/default; range_end:; response_count:1; response_revision:1199; }","duration":"286.106065ms","start":"2026-04-26T00:43:22.265678Z","end":"2026-04-26T00:43:22.551784Z","steps":["trace[1025998316] 'agreement among raft nodes before linearized reading' (duration: 286.020404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:22.551798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.904778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/local-path-storage/\" range_end:\"/registry/ingress/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:43:22.551833Z","caller":"traceutil/trace.go:171","msg":"trace[463336988] range","detail":"{range_begin:/registry/ingress/local-path-storage/; range_end:/registry/ingress/local-path-storage0; response_count:0; response_revision:1199; }","duration":"215.968109ms","start":"2026-04-26T00:43:22.335854Z","end":"2026-04-26T00:43:22.551822Z","steps":["trace[463336988] 'agreement among raft nodes before linearized reading' (duration: 215.900238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:22.551866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.915693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-04-26T00:43:22.551863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.150956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:43:22.551909Z","caller":"traceutil/trace.go:171","msg":"trace[124800960] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:1199; }","duration":"218.001164ms","start":"2026-04-26T00:43:22.333898Z","end":"2026-04-26T00:43:22.551899Z","steps":["trace[124800960] 'agreement among raft nodes before linearized reading' (duration: 217.888702ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:43:22.551957Z","caller":"traceutil/trace.go:171","msg":"trace[299894549] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1199; }","duration":"227.297537ms","start":"2026-04-26T00:43:22.324634Z","end":"2026-04-26T00:43:22.551931Z","steps":["trace[299894549] 'agreement among raft nodes before linearized reading' (duration: 227.110205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:22.552075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.286803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/local-path-storage/\" range_end:\"/registry/jobs/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-26T00:43:22.552113Z","caller":"traceutil/trace.go:171","msg":"trace[1428164363] range","detail":"{range_begin:/registry/jobs/local-path-storage/; range_end:/registry/jobs/local-path-storage0; response_count:0; response_revision:1199; }","duration":"221.341455ms","start":"2026-04-26T00:43:22.330763Z","end":"2026-04-26T00:43:22.552104Z","steps":["trace[1428164363] 'agreement among raft nodes before linearized reading' (duration: 221.288154ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:43:23.113478Z","caller":"traceutil/trace.go:171","msg":"trace[1103785186] linearizableReadLoop","detail":"{readStateIndex:1240; appliedIndex:1239; }","duration":"165.963352ms","start":"2026-04-26T00:43:22.947496Z","end":"2026-04-26T00:43:23.11346Z","steps":["trace[1103785186] 'read index received' (duration: 79.801184ms)","trace[1103785186] 'applied index is now lower than readState.Index' (duration: 86.161038ms)"],"step_count":2} {"level":"warn","ts":"2026-04-26T00:43:23.11363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.118984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-system/\" range_end:\"/registry/persistentvolumeclaims/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-26T00:43:23.113658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.866304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-proxy\" ","response":"range_response_count:1 size:1949"} {"level":"info","ts":"2026-04-26T00:43:23.113677Z","caller":"traceutil/trace.go:171","msg":"trace[1508621042] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-system/; range_end:/registry/persistentvolumeclaims/kube-system0; response_count:0; response_revision:1199; }","duration":"166.209655ms","start":"2026-04-26T00:43:22.947457Z","end":"2026-04-26T00:43:23.113666Z","steps":["trace[1508621042] 'agreement among raft nodes before linearized reading' (duration: 166.121974ms)"],"step_count":1} {"level":"info","ts":"2026-04-26T00:43:23.113691Z","caller":"traceutil/trace.go:171","msg":"trace[581558486] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1199; }","duration":"139.962785ms","start":"2026-04-26T00:43:22.973718Z","end":"2026-04-26T00:43:23.113681Z","steps":["trace[581558486] 'agreement among raft nodes before linearized reading' (duration: 139.888144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:23.113809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.399362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-root-ca.crt\" ","response":"range_response_count:1 size:1746"} {"level":"info","ts":"2026-04-26T00:43:23.113887Z","caller":"traceutil/trace.go:171","msg":"trace[777903360] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-root-ca.crt; range_end:; response_count:1; response_revision:1199; }","duration":"114.530834ms","start":"2026-04-26T00:43:22.999337Z","end":"2026-04-26T00:43:23.113868Z","steps":["trace[777903360] 'agreement among raft nodes before linearized reading' (duration: 114.345692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-26T00:43:30.361352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.669308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:1 size:201"} {"level":"info","ts":"2026-04-26T00:43:30.361457Z","caller":"traceutil/trace.go:171","msg":"trace[1963774680] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:1; response_revision:1223; }","duration":"104.83477ms","start":"2026-04-26T00:43:30.256603Z","end":"2026-04-26T00:43:30.361438Z","steps":["trace[1963774680] 'range keys from in-memory index tree' (duration: 104.489826ms)"],"step_count":1}