{"level":"warn","ts":"2026-03-19T17:51:48.587213Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T17:51:48.587314Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.96:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.96:2380","--initial-cluster=instance=https://199.19.213.96:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.96:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.96: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-03-19T17:51:48.587386Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T17:51:48.587399Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.96:2380"]} {"level":"info","ts":"2026-03-19T17:51:48.587433Z","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-03-19T17:51:48.588129Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.96:2379"]} {"level":"info","ts":"2026-03-19T17:51:48.58822Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.96:2380"],"listen-peer-urls":["https://199.19.213.96:2380"],"advertise-client-urls":["https://199.19.213.96:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.96:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.96: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-03-19T17:51:48.593525Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.090034ms"} {"level":"info","ts":"2026-03-19T17:51:48.602078Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"59b38ffafb07452b","cluster-id":"f6b359b7e8f93d01"} {"level":"info","ts":"2026-03-19T17:51:48.60216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b switched to configuration voters=()"} {"level":"info","ts":"2026-03-19T17:51:48.602182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became follower at term 0"} {"level":"info","ts":"2026-03-19T17:51:48.602197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 59b38ffafb07452b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-19T17:51:48.602206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became follower at term 1"} {"level":"info","ts":"2026-03-19T17:51:48.602248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b switched to configuration voters=(6463668198322947371)"} {"level":"warn","ts":"2026-03-19T17:51:48.62441Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-19T17:51:48.626087Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-19T17:51:48.627089Z","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-03-19T17:51:48.629774Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"59b38ffafb07452b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-19T17:51:48.630134Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"59b38ffafb07452b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-19T17:51:48.630247Z","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-03-19T17:51:48.630296Z","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-03-19T17:51:48.630329Z","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-03-19T17:51:48.631079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b switched to configuration voters=(6463668198322947371)"} {"level":"info","ts":"2026-03-19T17:51:48.631198Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f6b359b7e8f93d01","local-member-id":"59b38ffafb07452b","added-peer-id":"59b38ffafb07452b","added-peer-peer-urls":["https://199.19.213.96:2380"]} {"level":"info","ts":"2026-03-19T17:51:48.638985Z","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-03-19T17:51:48.639142Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.96:2380"} {"level":"info","ts":"2026-03-19T17:51:48.639181Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.96:2380"} {"level":"info","ts":"2026-03-19T17:51:48.639193Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"59b38ffafb07452b","initial-advertise-peer-urls":["https://199.19.213.96:2380"],"listen-peer-urls":["https://199.19.213.96:2380"],"advertise-client-urls":["https://199.19.213.96:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.96:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-19T17:51:48.639231Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-19T17:51:49.403049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b is starting a new election at term 1"} {"level":"info","ts":"2026-03-19T17:51:49.40313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became pre-candidate at term 1"} {"level":"info","ts":"2026-03-19T17:51:49.40318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b received MsgPreVoteResp from 59b38ffafb07452b at term 1"} {"level":"info","ts":"2026-03-19T17:51:49.403209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became candidate at term 2"} {"level":"info","ts":"2026-03-19T17:51:49.403225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b received MsgVoteResp from 59b38ffafb07452b at term 2"} {"level":"info","ts":"2026-03-19T17:51:49.403248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became leader at term 2"} {"level":"info","ts":"2026-03-19T17:51:49.403267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 59b38ffafb07452b elected leader 59b38ffafb07452b at term 2"} {"level":"info","ts":"2026-03-19T17:51:49.404352Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:51:49.40469Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"59b38ffafb07452b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.96:2379]}","request-path":"/0/members/59b38ffafb07452b/attributes","cluster-id":"f6b359b7e8f93d01","publish-timeout":"7s"} {"level":"info","ts":"2026-03-19T17:51:49.404724Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T17:51:49.40496Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T17:51:49.405369Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-19T17:51:49.40545Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-19T17:51:49.405479Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f6b359b7e8f93d01","local-member-id":"59b38ffafb07452b","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:51:49.406681Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:51:49.406748Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:51:49.407227Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.96:2379"} {"level":"info","ts":"2026-03-19T17:51:49.408408Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-19T17:52:08.758046Z","caller":"traceutil/trace.go:171","msg":"trace[810598321] linearizableReadLoop","detail":"{readStateIndex:464; appliedIndex:463; }","duration":"122.134635ms","start":"2026-03-19T17:52:08.635886Z","end":"2026-03-19T17:52:08.75802Z","steps":["trace[810598321] 'read index received' (duration: 121.863648ms)","trace[810598321] 'applied index is now lower than readState.Index' (duration: 269.297µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:52:08.758143Z","caller":"traceutil/trace.go:171","msg":"trace[1612571994] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"260.032324ms","start":"2026-03-19T17:52:08.498075Z","end":"2026-03-19T17:52:08.758107Z","steps":["trace[1612571994] 'process raft request' (duration: 259.762197ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:08.759252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.333187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:52:08.759416Z","caller":"traceutil/trace.go:171","msg":"trace[279110104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:450; }","duration":"123.526121ms","start":"2026-03-19T17:52:08.635837Z","end":"2026-03-19T17:52:08.759363Z","steps":["trace[279110104] 'agreement among raft nodes before linearized reading' (duration: 123.332906ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:09.321002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.968643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:52:09.321119Z","caller":"traceutil/trace.go:171","msg":"trace[1003773638] linearizableReadLoop","detail":"{readStateIndex:465; appliedIndex:464; }","duration":"281.287982ms","start":"2026-03-19T17:52:09.039813Z","end":"2026-03-19T17:52:09.321101Z","steps":["trace[1003773638] 'read index received' (duration: 46.701µs)","trace[1003773638] 'applied index is now lower than readState.Index' (duration: 281.239621ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:52:09.321222Z","caller":"traceutil/trace.go:171","msg":"trace[1464896296] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"558.251501ms","start":"2026-03-19T17:52:08.762957Z","end":"2026-03-19T17:52:09.321209Z","steps":["trace[1464896296] 'process raft request' (duration: 220.472956ms)","trace[1464896296] 'compare' (duration: 336.817929ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:52:09.321322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:08.762934Z","time spent":"558.325023ms","remote":"127.0.0.1:40868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:52:09.321652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.847937ms","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-03-19T17:52:09.321691Z","caller":"traceutil/trace.go:171","msg":"trace[1078530836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:451; }","duration":"281.941669ms","start":"2026-03-19T17:52:09.039739Z","end":"2026-03-19T17:52:09.321681Z","steps":["trace[1078530836] 'agreement among raft nodes before linearized reading' (duration: 281.866637ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:09.321788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.87965ms","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-03-19T17:52:09.321854Z","caller":"traceutil/trace.go:171","msg":"trace[1930250454] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:451; }","duration":"275.958032ms","start":"2026-03-19T17:52:09.045878Z","end":"2026-03-19T17:52:09.321836Z","steps":["trace[1930250454] 'agreement among raft nodes before linearized reading' (duration: 275.842939ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:09.437624Z","caller":"traceutil/trace.go:171","msg":"trace[238574659] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"110.196492ms","start":"2026-03-19T17:52:09.327397Z","end":"2026-03-19T17:52:09.437594Z","steps":["trace[238574659] 'process raft request' (duration: 107.142812ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:09.441043Z","caller":"traceutil/trace.go:171","msg":"trace[212808146] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"112.296037ms","start":"2026-03-19T17:52:09.328725Z","end":"2026-03-19T17:52:09.441021Z","steps":["trace[212808146] 'process raft request' (duration: 112.180044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:21.819486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.059093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:52:21.819577Z","caller":"traceutil/trace.go:171","msg":"trace[1564518490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"182.200007ms","start":"2026-03-19T17:52:21.637358Z","end":"2026-03-19T17:52:21.819558Z","steps":["trace[1564518490] 'range keys from in-memory index tree' (duration: 181.900989ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:21.821776Z","caller":"traceutil/trace.go:171","msg":"trace[243001194] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"154.442572ms","start":"2026-03-19T17:52:21.667306Z","end":"2026-03-19T17:52:21.821749Z","steps":["trace[243001194] 'process raft request' (duration: 154.312229ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:28.369172Z","caller":"traceutil/trace.go:171","msg":"trace[1800034205] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"181.630122ms","start":"2026-03-19T17:52:28.187505Z","end":"2026-03-19T17:52:28.369136Z","steps":["trace[1800034205] 'process raft request' (duration: 181.414176ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:29.264451Z","caller":"traceutil/trace.go:171","msg":"trace[1163025139] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"428.500383ms","start":"2026-03-19T17:52:28.835932Z","end":"2026-03-19T17:52:29.264432Z","steps":["trace[1163025139] 'read index received' (duration: 428.318248ms)","trace[1163025139] 'applied index is now lower than readState.Index' (duration: 181.325µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:52:29.264598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.648217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T17:52:29.26462Z","caller":"traceutil/trace.go:171","msg":"trace[1863456171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"428.74124ms","start":"2026-03-19T17:52:28.835873Z","end":"2026-03-19T17:52:29.264615Z","steps":["trace[1863456171] 'agreement among raft nodes before linearized reading' (duration: 428.642937ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:29.264642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:28.835848Z","time spent":"428.78924ms","remote":"127.0.0.1:40868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-19T17:52:29.264766Z","caller":"traceutil/trace.go:171","msg":"trace[900459393] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"555.712143ms","start":"2026-03-19T17:52:28.70901Z","end":"2026-03-19T17:52:29.264722Z","steps":["trace[900459393] 'process raft request' (duration: 555.289682ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:29.264993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:28.708712Z","time spent":"556.181836ms","remote":"127.0.0.1:40868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:52:29.446809Z","caller":"traceutil/trace.go:171","msg":"trace[8380294] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"177.588974ms","start":"2026-03-19T17:52:29.269196Z","end":"2026-03-19T17:52:29.446785Z","steps":["trace[8380294] 'process raft request' (duration: 177.336418ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:47.434398Z","caller":"traceutil/trace.go:171","msg":"trace[1066034088] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"139.903767ms","start":"2026-03-19T17:52:47.294463Z","end":"2026-03-19T17:52:47.434366Z","steps":["trace[1066034088] 'process raft request' (duration: 139.613759ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:54.006799Z","caller":"traceutil/trace.go:171","msg":"trace[2066700249] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"411.508823ms","start":"2026-03-19T17:52:53.595265Z","end":"2026-03-19T17:52:54.006774Z","steps":["trace[2066700249] 'process raft request' (duration: 411.364589ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:54.006992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:53.595241Z","time spent":"411.660567ms","remote":"127.0.0.1:40868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:52:54.175856Z","caller":"traceutil/trace.go:171","msg":"trace[1392351074] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:773; }","duration":"549.319494ms","start":"2026-03-19T17:52:53.62651Z","end":"2026-03-19T17:52:54.17583Z","steps":["trace[1392351074] 'read index received' (duration: 380.151552ms)","trace[1392351074] 'applied index is now lower than readState.Index' (duration: 169.166512ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:52:54.176022Z","caller":"traceutil/trace.go:171","msg":"trace[1684914691] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"580.12447ms","start":"2026-03-19T17:52:53.595865Z","end":"2026-03-19T17:52:54.175989Z","steps":["trace[1684914691] 'process raft request' (duration: 567.439304ms)","trace[1684914691] 'compare' (duration: 12.420909ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:52:54.176106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.582451ms","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-03-19T17:52:54.17614Z","caller":"traceutil/trace.go:171","msg":"trace[1204248876] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:751; }","duration":"549.673354ms","start":"2026-03-19T17:52:53.626456Z","end":"2026-03-19T17:52:54.17613Z","steps":["trace[1204248876] 'agreement among raft nodes before linearized reading' (duration: 549.510499ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:54.176144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:53.59584Z","time spent":"580.245994ms","remote":"127.0.0.1:40868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:52:54.176162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:53.626439Z","time spent":"549.717534ms","remote":"127.0.0.1:40868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T17:52:54.176269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.905518ms","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-03-19T17:52:54.17632Z","caller":"traceutil/trace.go:171","msg":"trace[990766911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:751; }","duration":"538.98639ms","start":"2026-03-19T17:52:53.637311Z","end":"2026-03-19T17:52:54.176298Z","steps":["trace[990766911] 'agreement among raft nodes before linearized reading' (duration: 538.839026ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:54.176353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:53.637297Z","time spent":"539.047471ms","remote":"127.0.0.1:40868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-19T17:53:28.458134Z","caller":"traceutil/trace.go:171","msg":"trace[73676954] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"118.820721ms","start":"2026-03-19T17:53:28.339282Z","end":"2026-03-19T17:53:28.458103Z","steps":["trace[73676954] 'process raft request' (duration: 106.61446ms)","trace[73676954] 'compare' (duration: 11.706607ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:53:28.458298Z","caller":"traceutil/trace.go:171","msg":"trace[1145793952] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"117.598645ms","start":"2026-03-19T17:53:28.340658Z","end":"2026-03-19T17:53:28.458257Z","steps":["trace[1145793952] 'process raft request' (duration: 117.205493ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:40.459015Z","caller":"traceutil/trace.go:171","msg":"trace[94150483] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"110.722722ms","start":"2026-03-19T17:53:40.348261Z","end":"2026-03-19T17:53:40.458984Z","steps":["trace[94150483] 'process raft request' (duration: 109.65433ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:48.577494Z","caller":"traceutil/trace.go:171","msg":"trace[1509596590] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"229.572674ms","start":"2026-03-19T17:53:48.347858Z","end":"2026-03-19T17:53:48.57743Z","steps":["trace[1509596590] 'process raft request' (duration: 229.281475ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:49.214846Z","caller":"traceutil/trace.go:171","msg":"trace[1469125946] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"193.222106ms","start":"2026-03-19T17:53:49.021596Z","end":"2026-03-19T17:53:49.214818Z","steps":["trace[1469125946] 'process raft request' (duration: 174.630426ms)","trace[1469125946] 'compare' (duration: 17.929731ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:53:49.437747Z","caller":"traceutil/trace.go:171","msg":"trace[399097597] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"218.210528ms","start":"2026-03-19T17:53:49.219505Z","end":"2026-03-19T17:53:49.437715Z","steps":["trace[399097597] 'process raft request' (duration: 175.77981ms)","trace[399097597] 'compare' (duration: 42.156219ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:53:52.845991Z","caller":"traceutil/trace.go:171","msg":"trace[2100990805] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"128.112826ms","start":"2026-03-19T17:53:52.717853Z","end":"2026-03-19T17:53:52.845966Z","steps":["trace[2100990805] 'process raft request' (duration: 127.91709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:47.80969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.988379ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-19T17:55:47.809802Z","caller":"traceutil/trace.go:171","msg":"trace[572716863] linearizableReadLoop","detail":"{readStateIndex:1634; appliedIndex:1632; }","duration":"370.266591ms","start":"2026-03-19T17:55:47.439516Z","end":"2026-03-19T17:55:47.809783Z","steps":["trace[572716863] 'read index received' (duration: 49.325043ms)","trace[572716863] 'applied index is now lower than readState.Index' (duration: 320.940188ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:55:47.810034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.488648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-19T17:55:47.810064Z","caller":"traceutil/trace.go:171","msg":"trace[1875927153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1572; }","duration":"370.588731ms","start":"2026-03-19T17:55:47.439466Z","end":"2026-03-19T17:55:47.810055Z","steps":["trace[1875927153] 'agreement among raft nodes before linearized reading' (duration: 370.408716ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:47.810023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.643167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T17:55:47.810099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:47.439448Z","time spent":"370.643052ms","remote":"127.0.0.1:40868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-19T17:55:47.810083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.476492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T17:55:47.810124Z","caller":"traceutil/trace.go:171","msg":"trace[90421749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1572; }","duration":"172.783591ms","start":"2026-03-19T17:55:47.63732Z","end":"2026-03-19T17:55:47.810104Z","steps":["trace[90421749] 'agreement among raft nodes before linearized reading' (duration: 172.587656ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:47.810108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.106469ms","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-03-19T17:55:47.810176Z","caller":"traceutil/trace.go:171","msg":"trace[952400922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1572; }","duration":"178.609146ms","start":"2026-03-19T17:55:47.631533Z","end":"2026-03-19T17:55:47.810142Z","steps":["trace[952400922] 'agreement among raft nodes before linearized reading' (duration: 178.380839ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:55:47.810196Z","caller":"traceutil/trace.go:171","msg":"trace[1598873165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1572; }","duration":"271.238763ms","start":"2026-03-19T17:55:47.538935Z","end":"2026-03-19T17:55:47.810174Z","steps":["trace[1598873165] 'agreement among raft nodes before linearized reading' (duration: 270.960634ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:55:47.942606Z","caller":"traceutil/trace.go:171","msg":"trace[1327846172] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"128.076616ms","start":"2026-03-19T17:55:47.814497Z","end":"2026-03-19T17:55:47.942574Z","steps":["trace[1327846172] 'process raft request' (duration: 98.375749ms)","trace[1327846172] 'compare' (duration: 29.513521ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:55:47.942791Z","caller":"traceutil/trace.go:171","msg":"trace[1793190268] transaction","detail":"{read_only:false; response_revision:1575; number_of_response:1; }","duration":"127.914631ms","start":"2026-03-19T17:55:47.81484Z","end":"2026-03-19T17:55:47.942754Z","steps":["trace[1793190268] 'process raft request' (duration: 127.687994ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:56:54.277483Z","caller":"traceutil/trace.go:171","msg":"trace[1346193996] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"118.379007ms","start":"2026-03-19T17:56:54.159071Z","end":"2026-03-19T17:56:54.27745Z","steps":["trace[1346193996] 'process raft request' (duration: 118.15536ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:56:54.40123Z","caller":"traceutil/trace.go:171","msg":"trace[1209258724] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"115.461899ms","start":"2026-03-19T17:56:54.285738Z","end":"2026-03-19T17:56:54.4012Z","steps":["trace[1209258724] 'process raft request' (duration: 74.773174ms)","trace[1209258724] 'compare' (duration: 40.563421ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:57:01.046933Z","caller":"traceutil/trace.go:171","msg":"trace[1469801580] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"162.946068ms","start":"2026-03-19T17:57:00.883959Z","end":"2026-03-19T17:57:01.046905Z","steps":["trace[1469801580] 'process raft request' (duration: 162.789233ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:17.23236Z","caller":"traceutil/trace.go:171","msg":"trace[1236248283] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"116.47783ms","start":"2026-03-19T17:57:17.11585Z","end":"2026-03-19T17:57:17.232328Z","steps":["trace[1236248283] 'process raft request' (duration: 112.354577ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:19.194535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.495139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/default/\" range_end:\"/registry/roles/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:57:19.194655Z","caller":"traceutil/trace.go:171","msg":"trace[313242508] range","detail":"{range_begin:/registry/roles/default/; range_end:/registry/roles/default0; response_count:0; response_revision:2029; }","duration":"121.628534ms","start":"2026-03-19T17:57:19.072992Z","end":"2026-03-19T17:57:19.19462Z","steps":["trace[313242508] 'range keys from in-memory index tree' (duration: 121.437337ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:19.833812Z","caller":"traceutil/trace.go:171","msg":"trace[1938876711] linearizableReadLoop","detail":"{readStateIndex:2112; appliedIndex:2111; }","duration":"241.739621ms","start":"2026-03-19T17:57:19.592056Z","end":"2026-03-19T17:57:19.833795Z","steps":["trace[1938876711] 'read index received' (duration: 241.626838ms)","trace[1938876711] 'applied index is now lower than readState.Index' (duration: 111.413µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:57:19.833907Z","caller":"traceutil/trace.go:171","msg":"trace[1280470557] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"242.429762ms","start":"2026-03-19T17:57:19.59147Z","end":"2026-03-19T17:57:19.8339Z","steps":["trace[1280470557] 'process raft request' (duration: 242.202715ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:19.834031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.964668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/default/kubernetes\" ","response":"range_response_count:1 size:704"} {"level":"info","ts":"2026-03-19T17:57:19.834071Z","caller":"traceutil/trace.go:171","msg":"trace[1176711722] range","detail":"{range_begin:/registry/services/specs/default/kubernetes; range_end:; response_count:1; response_revision:2031; }","duration":"242.02541ms","start":"2026-03-19T17:57:19.592036Z","end":"2026-03-19T17:57:19.834061Z","steps":["trace[1176711722] 'agreement among raft nodes before linearized reading' (duration: 241.948588ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:19.8343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.752204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-public/cluster-info\" ","response":"range_response_count:1 size:2177"} {"level":"info","ts":"2026-03-19T17:57:19.834334Z","caller":"traceutil/trace.go:171","msg":"trace[354839397] range","detail":"{range_begin:/registry/configmaps/kube-public/cluster-info; range_end:; response_count:1; response_revision:2031; }","duration":"225.801626ms","start":"2026-03-19T17:57:19.608524Z","end":"2026-03-19T17:57:19.834326Z","steps":["trace[354839397] 'agreement among raft nodes before linearized reading' (duration: 225.735004ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:19.834388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.210853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/kube-system/\" range_end:\"/registry/cronjobs/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:57:19.834434Z","caller":"traceutil/trace.go:171","msg":"trace[295532084] range","detail":"{range_begin:/registry/cronjobs/kube-system/; range_end:/registry/cronjobs/kube-system0; response_count:0; response_revision:2031; }","duration":"181.303346ms","start":"2026-03-19T17:57:19.653119Z","end":"2026-03-19T17:57:19.834422Z","steps":["trace[295532084] 'agreement among raft nodes before linearized reading' (duration: 181.214483ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:19.834453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.891092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T17:57:19.834524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.103937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/kube-public/\" range_end:\"/registry/cronjobs/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:57:19.834598Z","caller":"traceutil/trace.go:171","msg":"trace[1991575809] range","detail":"{range_begin:/registry/cronjobs/kube-public/; range_end:/registry/cronjobs/kube-public0; response_count:0; response_revision:2031; }","duration":"202.184119ms","start":"2026-03-19T17:57:19.632396Z","end":"2026-03-19T17:57:19.83458Z","steps":["trace[1991575809] 'agreement among raft nodes before linearized reading' (duration: 202.056276ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:19.834537Z","caller":"traceutil/trace.go:171","msg":"trace[1543435306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2031; }","duration":"198.002325ms","start":"2026-03-19T17:57:19.636515Z","end":"2026-03-19T17:57:19.834517Z","steps":["trace[1543435306] 'agreement among raft nodes before linearized reading' (duration: 197.879692ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:19.973582Z","caller":"traceutil/trace.go:171","msg":"trace[689817399] linearizableReadLoop","detail":"{readStateIndex:2113; appliedIndex:2112; }","duration":"133.995143ms","start":"2026-03-19T17:57:19.83956Z","end":"2026-03-19T17:57:19.973555Z","steps":["trace[689817399] 'read index received' (duration: 128.524019ms)","trace[689817399] 'applied index is now lower than readState.Index' (duration: 5.469894ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:57:19.973644Z","caller":"traceutil/trace.go:171","msg":"trace[1038718944] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"134.539239ms","start":"2026-03-19T17:57:19.839081Z","end":"2026-03-19T17:57:19.97362Z","steps":["trace[1038718944] 'process raft request' (duration: 128.995843ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:19.973713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.129367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/default/kubernetes\" ","response":"range_response_count:1 size:704"} {"level":"info","ts":"2026-03-19T17:57:19.973743Z","caller":"traceutil/trace.go:171","msg":"trace[411975391] range","detail":"{range_begin:/registry/services/specs/default/kubernetes; range_end:; response_count:1; response_revision:2032; }","duration":"134.212239ms","start":"2026-03-19T17:57:19.839524Z","end":"2026-03-19T17:57:19.973736Z","steps":["trace[411975391] 'agreement among raft nodes before linearized reading' (duration: 134.129957ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:20.285204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.312245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:57:20.285452Z","caller":"traceutil/trace.go:171","msg":"trace[41742441] linearizableReadLoop","detail":"{readStateIndex:2116; appliedIndex:2114; }","duration":"226.172877ms","start":"2026-03-19T17:57:20.059257Z","end":"2026-03-19T17:57:20.28543Z","steps":["trace[41742441] 'read index received' (duration: 33.553953ms)","trace[41742441] 'applied index is now lower than readState.Index' (duration: 192.616914ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:57:20.285494Z","caller":"traceutil/trace.go:171","msg":"trace[138068647] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"304.829567ms","start":"2026-03-19T17:57:19.980628Z","end":"2026-03-19T17:57:20.285457Z","steps":["trace[138068647] 'process raft request' (duration: 304.720933ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:20.285466Z","caller":"traceutil/trace.go:171","msg":"trace[814117323] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"306.508647ms","start":"2026-03-19T17:57:19.978918Z","end":"2026-03-19T17:57:20.285427Z","steps":["trace[814117323] 'process raft request' (duration: 113.882732ms)","trace[814117323] 'compare' (duration: 192.228263ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:57:20.285649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:57:19.980619Z","time spent":"304.93447ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":606,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:57:20.28565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:57:19.978907Z","time spent":"306.652181ms","remote":"127.0.0.1:40868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:57:20.285704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.794184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" limit:500 ","response":"range_response_count:3 size:19437"} {"level":"warn","ts":"2026-03-19T17:57:20.285717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.454315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-public/cluster-info\" ","response":"range_response_count:1 size:2177"} {"level":"info","ts":"2026-03-19T17:57:20.285758Z","caller":"traceutil/trace.go:171","msg":"trace[1443322902] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:3; response_revision:2035; }","duration":"244.876395ms","start":"2026-03-19T17:57:20.040867Z","end":"2026-03-19T17:57:20.285743Z","steps":["trace[1443322902] 'agreement among raft nodes before linearized reading' (duration: 244.680139ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:20.285787Z","caller":"traceutil/trace.go:171","msg":"trace[65771295] range","detail":"{range_begin:/registry/configmaps/kube-public/cluster-info; range_end:; response_count:1; response_revision:2035; }","duration":"304.535678ms","start":"2026-03-19T17:57:19.981233Z","end":"2026-03-19T17:57:20.285769Z","steps":["trace[65771295] 'agreement among raft nodes before linearized reading' (duration: 304.426785ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:20.285845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:57:19.981227Z","time spent":"304.60539ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":2200,"request content":"key:\"/registry/configmaps/kube-public/cluster-info\" "} {"level":"warn","ts":"2026-03-19T17:57:20.28601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.209609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-public/\" range_end:\"/registry/deployments/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:57:20.286084Z","caller":"traceutil/trace.go:171","msg":"trace[150348909] range","detail":"{range_begin:/registry/deployments/kube-public/; range_end:/registry/deployments/kube-public0; response_count:0; response_revision:2035; }","duration":"220.309922ms","start":"2026-03-19T17:57:20.065753Z","end":"2026-03-19T17:57:20.286063Z","steps":["trace[150348909] 'agreement among raft nodes before linearized reading' (duration: 220.214529ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:20.286459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.454476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/\" range_end:\"/registry/events/default0\" limit:500 ","response":"range_response_count:24 size:16337"} {"level":"info","ts":"2026-03-19T17:57:20.286544Z","caller":"traceutil/trace.go:171","msg":"trace[2105344365] range","detail":"{range_begin:/registry/events/default/; range_end:/registry/events/default0; response_count:24; response_revision:2035; }","duration":"223.574739ms","start":"2026-03-19T17:57:20.062952Z","end":"2026-03-19T17:57:20.286526Z","steps":["trace[2105344365] 'agreement among raft nodes before linearized reading' (duration: 222.62468ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:20.535094Z","caller":"traceutil/trace.go:171","msg":"trace[301285736] linearizableReadLoop","detail":"{readStateIndex:2117; appliedIndex:2116; }","duration":"174.791571ms","start":"2026-03-19T17:57:20.360275Z","end":"2026-03-19T17:57:20.535066Z","steps":["trace[301285736] 'read index received' (duration: 174.532703ms)","trace[301285736] 'applied index is now lower than readState.Index' (duration: 257.268µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:57:20.53516Z","caller":"traceutil/trace.go:171","msg":"trace[1211741633] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"240.296408ms","start":"2026-03-19T17:57:20.294839Z","end":"2026-03-19T17:57:20.535136Z","steps":["trace[1211741633] 'process raft request' (duration: 240.054421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:20.535314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.163993ms","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":"warn","ts":"2026-03-19T17:57:20.535345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.123067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-03-19T17:57:20.53532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.021589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-config\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-03-19T17:57:20.535377Z","caller":"traceutil/trace.go:171","msg":"trace[1187206335] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2036; }","duration":"124.166829ms","start":"2026-03-19T17:57:20.4112Z","end":"2026-03-19T17:57:20.535367Z","steps":["trace[1187206335] 'agreement among raft nodes before linearized reading' (duration: 124.083097ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:20.535384Z","caller":"traceutil/trace.go:171","msg":"trace[1751621601] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-config; range_end:; response_count:1; response_revision:2036; }","duration":"175.131121ms","start":"2026-03-19T17:57:20.360242Z","end":"2026-03-19T17:57:20.535373Z","steps":["trace[1751621601] 'agreement among raft nodes before linearized reading' (duration: 174.962926ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:20.535382Z","caller":"traceutil/trace.go:171","msg":"trace[964383910] range","detail":"{range_begin:/registry/services/endpoints/kube-public/; range_end:/registry/services/endpoints/kube-public0; response_count:0; response_revision:2036; }","duration":"173.262116ms","start":"2026-03-19T17:57:20.362101Z","end":"2026-03-19T17:57:20.535363Z","steps":["trace[964383910] 'agreement among raft nodes before linearized reading' (duration: 173.126852ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:20.535626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.757514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/\" range_end:\"/registry/services/endpoints/kube-system0\" limit:500 ","response":"range_response_count:3 size:2766"} {"level":"info","ts":"2026-03-19T17:57:20.535669Z","caller":"traceutil/trace.go:171","msg":"trace[2097628975] range","detail":"{range_begin:/registry/services/endpoints/kube-system/; range_end:/registry/services/endpoints/kube-system0; response_count:3; response_revision:2036; }","duration":"149.835446ms","start":"2026-03-19T17:57:20.385822Z","end":"2026-03-19T17:57:20.535657Z","steps":["trace[2097628975] 'agreement among raft nodes before linearized reading' (duration: 149.730313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:20.535721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.073003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-public/kube-root-ca.crt\" ","response":"range_response_count:1 size:1746"} {"level":"info","ts":"2026-03-19T17:57:20.53578Z","caller":"traceutil/trace.go:171","msg":"trace[1164903201] range","detail":"{range_begin:/registry/configmaps/kube-public/kube-root-ca.crt; range_end:; response_count:1; response_revision:2036; }","duration":"152.160895ms","start":"2026-03-19T17:57:20.383604Z","end":"2026-03-19T17:57:20.535765Z","steps":["trace[1164903201] 'agreement among raft nodes before linearized reading' (duration: 152.052312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:20.865994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.257784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:57:20.866252Z","caller":"traceutil/trace.go:171","msg":"trace[1179275211] linearizableReadLoop","detail":"{readStateIndex:2119; appliedIndex:2117; }","duration":"255.010438ms","start":"2026-03-19T17:57:20.611174Z","end":"2026-03-19T17:57:20.866185Z","steps":["trace[1179275211] 'read index received' (duration: 70.464185ms)","trace[1179275211] 'applied index is now lower than readState.Index' (duration: 184.544983ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:57:20.866323Z","caller":"traceutil/trace.go:171","msg":"trace[428843240] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"326.239906ms","start":"2026-03-19T17:57:20.540044Z","end":"2026-03-19T17:57:20.866284Z","steps":["trace[428843240] 'process raft request' (duration: 326.05782ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:20.866458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.267775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/kube-system/\" range_end:\"/registry/ingress/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T17:57:20.866507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:57:20.540033Z","time spent":"326.37546ms","remote":"127.0.0.1:40868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:57:20.866558Z","caller":"traceutil/trace.go:171","msg":"trace[407752878] range","detail":"{range_begin:/registry/ingress/kube-system/; range_end:/registry/ingress/kube-system0; response_count:0; response_revision:2038; }","duration":"255.374469ms","start":"2026-03-19T17:57:20.611143Z","end":"2026-03-19T17:57:20.866517Z","steps":["trace[407752878] 'agreement among raft nodes before linearized reading' (duration: 255.240355ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:20.866473Z","caller":"traceutil/trace.go:171","msg":"trace[265314702] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"326.38018ms","start":"2026-03-19T17:57:20.540006Z","end":"2026-03-19T17:57:20.866386Z","steps":["trace[265314702] 'process raft request' (duration: 141.623821ms)","trace[265314702] 'compare' (duration: 184.12609ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:57:20.866589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.569585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-config\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-03-19T17:57:20.867031Z","caller":"traceutil/trace.go:171","msg":"trace[1279235202] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-config; range_end:; response_count:1; response_revision:2038; }","duration":"254.039899ms","start":"2026-03-19T17:57:20.612972Z","end":"2026-03-19T17:57:20.867012Z","steps":["trace[1279235202] 'agreement among raft nodes before linearized reading' (duration: 253.450162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:20.866686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.758562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/kube-public/\" range_end:\"/registry/ingress/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:57:20.867194Z","caller":"traceutil/trace.go:171","msg":"trace[657936868] range","detail":"{range_begin:/registry/ingress/kube-public/; range_end:/registry/ingress/kube-public0; response_count:0; response_revision:2038; }","duration":"242.251917ms","start":"2026-03-19T17:57:20.624913Z","end":"2026-03-19T17:57:20.867165Z","steps":["trace[657936868] 'agreement among raft nodes before linearized reading' (duration: 241.734781ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:20.866721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.819474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-public/kube-root-ca.crt\" ","response":"range_response_count:1 size:1746"} {"level":"info","ts":"2026-03-19T17:57:20.867312Z","caller":"traceutil/trace.go:171","msg":"trace[978119448] range","detail":"{range_begin:/registry/configmaps/kube-public/kube-root-ca.crt; range_end:; response_count:1; response_revision:2038; }","duration":"240.428862ms","start":"2026-03-19T17:57:20.626873Z","end":"2026-03-19T17:57:20.867302Z","steps":["trace[978119448] 'agreement among raft nodes before linearized reading' (duration: 239.782273ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:20.86674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.549462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-19T17:57:20.867419Z","caller":"traceutil/trace.go:171","msg":"trace[904584968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2038; }","duration":"189.281034ms","start":"2026-03-19T17:57:20.678122Z","end":"2026-03-19T17:57:20.867403Z","steps":["trace[904584968] 'agreement among raft nodes before linearized reading' (duration: 188.370657ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:20.866772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.89316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T17:57:20.86725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:57:20.539987Z","time spent":"326.936077ms","remote":"127.0.0.1:40868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:57:20.867527Z","caller":"traceutil/trace.go:171","msg":"trace[721776949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2038; }","duration":"137.679382ms","start":"2026-03-19T17:57:20.729835Z","end":"2026-03-19T17:57:20.867514Z","steps":["trace[721776949] 'agreement among raft nodes before linearized reading' (duration: 136.854058ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:21.131472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.76306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:57:21.131626Z","caller":"traceutil/trace.go:171","msg":"trace[1264022574] linearizableReadLoop","detail":"{readStateIndex:2121; appliedIndex:2120; }","duration":"194.895823ms","start":"2026-03-19T17:57:20.936705Z","end":"2026-03-19T17:57:21.131601Z","steps":["trace[1264022574] 'read index received' (duration: 92.658818ms)","trace[1264022574] 'applied index is now lower than readState.Index' (duration: 102.234665ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:57:21.131653Z","caller":"traceutil/trace.go:171","msg":"trace[1283865436] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"219.063804ms","start":"2026-03-19T17:57:20.912546Z","end":"2026-03-19T17:57:21.131609Z","steps":["trace[1283865436] 'process raft request' (duration: 117.013545ms)","trace[1283865436] 'compare' (duration: 101.507573ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:57:21.131881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.545654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-config\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-03-19T17:57:21.131919Z","caller":"traceutil/trace.go:171","msg":"trace[383229505] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-config; range_end:; response_count:1; response_revision:2040; }","duration":"258.655917ms","start":"2026-03-19T17:57:20.873251Z","end":"2026-03-19T17:57:21.131907Z","steps":["trace[383229505] 'agreement among raft nodes before linearized reading' (duration: 258.483692ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:21.131976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.869562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-public/\" range_end:\"/registry/events/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:57:21.132092Z","caller":"traceutil/trace.go:171","msg":"trace[229205198] range","detail":"{range_begin:/registry/events/kube-public/; range_end:/registry/events/kube-public0; response_count:0; response_revision:2040; }","duration":"190.043898ms","start":"2026-03-19T17:57:20.942025Z","end":"2026-03-19T17:57:21.132068Z","steps":["trace[229205198] 'agreement among raft nodes before linearized reading' (duration: 189.866902ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:21.132119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.412751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/kube-system/\" range_end:\"/registry/jobs/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:57:21.132174Z","caller":"traceutil/trace.go:171","msg":"trace[2132628238] range","detail":"{range_begin:/registry/jobs/kube-system/; range_end:/registry/jobs/kube-system0; response_count:0; response_revision:2040; }","duration":"172.495563ms","start":"2026-03-19T17:57:20.959667Z","end":"2026-03-19T17:57:21.132162Z","steps":["trace[2132628238] 'agreement among raft nodes before linearized reading' (duration: 172.399741ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:21.13232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.536843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/kube-public/\" range_end:\"/registry/jobs/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:57:21.132432Z","caller":"traceutil/trace.go:171","msg":"trace[672181760] range","detail":"{range_begin:/registry/jobs/kube-public/; range_end:/registry/jobs/kube-public0; response_count:0; response_revision:2040; }","duration":"186.690517ms","start":"2026-03-19T17:57:20.945726Z","end":"2026-03-19T17:57:21.132416Z","steps":["trace[672181760] 'agreement among raft nodes before linearized reading' (duration: 186.488381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:21.463756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.583148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/kube-system/\" range_end:\"/registry/networkpolicies/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:57:21.463855Z","caller":"traceutil/trace.go:171","msg":"trace[1289230576] range","detail":"{range_begin:/registry/networkpolicies/kube-system/; range_end:/registry/networkpolicies/kube-system0; response_count:0; response_revision:2040; }","duration":"231.735242ms","start":"2026-03-19T17:57:21.232093Z","end":"2026-03-19T17:57:21.463828Z","steps":["trace[1289230576] 'range keys from in-memory index tree' (duration: 231.513806ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:21.463868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.713653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-csi-encryption-kms-config\" ","response":"range_response_count:1 size:810"} {"level":"warn","ts":"2026-03-19T17:57:21.463897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.459367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-19T17:57:21.463962Z","caller":"traceutil/trace.go:171","msg":"trace[882665600] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-csi-encryption-kms-config; range_end:; response_count:1; response_revision:2040; }","duration":"228.853987ms","start":"2026-03-19T17:57:21.235084Z","end":"2026-03-19T17:57:21.463938Z","steps":["trace[882665600] 'range keys from in-memory index tree' (duration: 228.546858ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:21.463975Z","caller":"traceutil/trace.go:171","msg":"trace[413176214] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2040; }","duration":"212.57148ms","start":"2026-03-19T17:57:21.251382Z","end":"2026-03-19T17:57:21.463953Z","steps":["trace[413176214] 'range keys from in-memory index tree' (duration: 212.317683ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:21.463754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.919187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-csi-config\" ","response":"range_response_count:1 size:917"} {"level":"info","ts":"2026-03-19T17:57:21.464046Z","caller":"traceutil/trace.go:171","msg":"trace[1665859605] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-csi-config; range_end:; response_count:1; response_revision:2040; }","duration":"162.277628ms","start":"2026-03-19T17:57:21.301749Z","end":"2026-03-19T17:57:21.464027Z","steps":["trace[1665859605] 'range keys from in-memory index tree' (duration: 161.67508ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:21.464194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.043131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/\" range_end:\"/registry/pods/kube-system0\" limit:500 ","response":"range_response_count:12 size:89035"} {"level":"info","ts":"2026-03-19T17:57:21.464272Z","caller":"traceutil/trace.go:171","msg":"trace[286895928] range","detail":"{range_begin:/registry/pods/kube-system/; range_end:/registry/pods/kube-system0; response_count:12; response_revision:2040; }","duration":"159.179155ms","start":"2026-03-19T17:57:21.305075Z","end":"2026-03-19T17:57:21.464254Z","steps":["trace[286895928] 'range keys from in-memory index tree' (duration: 158.753932ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:21.931724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.87151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:1 size:612"} {"level":"info","ts":"2026-03-19T17:57:21.931802Z","caller":"traceutil/trace.go:171","msg":"trace[195095043] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:1; response_revision:2041; }","duration":"288.993033ms","start":"2026-03-19T17:57:21.642791Z","end":"2026-03-19T17:57:21.931784Z","steps":["trace[195095043] 'range keys from in-memory index tree' (duration: 288.759597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:21.931777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.821774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-public/\" range_end:\"/registry/pods/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T17:57:21.93183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.280091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T17:57:21.93183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.209647ms","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":"info","ts":"2026-03-19T17:57:21.931879Z","caller":"traceutil/trace.go:171","msg":"trace[951263543] range","detail":"{range_begin:/registry/pods/kube-public/; range_end:/registry/pods/kube-public0; response_count:0; response_revision:2041; }","duration":"318.985319ms","start":"2026-03-19T17:57:21.612874Z","end":"2026-03-19T17:57:21.93186Z","steps":["trace[951263543] 'range keys from in-memory index tree' (duration: 318.753213ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:21.931893Z","caller":"traceutil/trace.go:171","msg":"trace[1582740414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2041; }","duration":"296.367204ms","start":"2026-03-19T17:57:21.635508Z","end":"2026-03-19T17:57:21.931876Z","steps":["trace[1582740414] 'range keys from in-memory index tree' (duration: 296.22283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:21.931931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:57:21.612858Z","time spent":"319.060002ms","remote":"127.0.0.1:40784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/pods/kube-public/\" range_end:\"/registry/pods/kube-public0\" limit:500 "} {"level":"info","ts":"2026-03-19T17:57:21.931909Z","caller":"traceutil/trace.go:171","msg":"trace[632534715] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-system/; range_end:/registry/persistentvolumeclaims/kube-system0; response_count:0; response_revision:2041; }","duration":"232.296299ms","start":"2026-03-19T17:57:21.699597Z","end":"2026-03-19T17:57:21.931894Z","steps":["trace[632534715] 'range keys from in-memory index tree' (duration: 232.078063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:21.931979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.631353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-19T17:57:21.932061Z","caller":"traceutil/trace.go:171","msg":"trace[1547877656] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:2041; }","duration":"285.768257ms","start":"2026-03-19T17:57:21.646275Z","end":"2026-03-19T17:57:21.932044Z","steps":["trace[1547877656] 'range keys from in-memory index tree' (duration: 285.52752ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:22.367232Z","caller":"traceutil/trace.go:171","msg":"trace[1218661084] linearizableReadLoop","detail":"{readStateIndex:2123; appliedIndex:2122; }","duration":"251.452582ms","start":"2026-03-19T17:57:22.115756Z","end":"2026-03-19T17:57:22.367209Z","steps":["trace[1218661084] 'read index received' (duration: 251.253106ms)","trace[1218661084] 'applied index is now lower than readState.Index' (duration: 198.536µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:57:22.367585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.475822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" ","response":"range_response_count:1 size:370"} {"level":"info","ts":"2026-03-19T17:57:22.367586Z","caller":"traceutil/trace.go:171","msg":"trace[903233732] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"301.024333ms","start":"2026-03-19T17:57:22.066548Z","end":"2026-03-19T17:57:22.367572Z","steps":["trace[903233732] 'process raft request' (duration: 300.520757ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:22.367618Z","caller":"traceutil/trace.go:171","msg":"trace[738590995] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:2042; }","duration":"249.530745ms","start":"2026-03-19T17:57:22.118078Z","end":"2026-03-19T17:57:22.367609Z","steps":["trace[738590995] 'agreement among raft nodes before linearized reading' (duration: 249.443612ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:22.367598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.804992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:1 size:3017"} {"level":"warn","ts":"2026-03-19T17:57:22.367679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:57:22.06654Z","time spent":"301.079674ms","remote":"127.0.0.1:40868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:57:22.367659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.305892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T17:57:22.367682Z","caller":"traceutil/trace.go:171","msg":"trace[1628567831] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:1; response_revision:2042; }","duration":"251.921986ms","start":"2026-03-19T17:57:22.11574Z","end":"2026-03-19T17:57:22.367661Z","steps":["trace[1628567831] 'agreement among raft nodes before linearized reading' (duration: 251.70923ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:22.367744Z","caller":"traceutil/trace.go:171","msg":"trace[634145222] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2042; }","duration":"214.412175ms","start":"2026-03-19T17:57:22.153314Z","end":"2026-03-19T17:57:22.367726Z","steps":["trace[634145222] 'agreement among raft nodes before linearized reading' (duration: 214.24841ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:22.367808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.754647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.96\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-19T17:57:22.367808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.087323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:57:22.367904Z","caller":"traceutil/trace.go:171","msg":"trace[1079221506] range","detail":"{range_begin:/registry/masterleases/199.19.213.96; range_end:; response_count:1; response_revision:2042; }","duration":"122.85644ms","start":"2026-03-19T17:57:22.245015Z","end":"2026-03-19T17:57:22.367872Z","steps":["trace[1079221506] 'agreement among raft nodes before linearized reading' (duration: 122.610243ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:22.367919Z","caller":"traceutil/trace.go:171","msg":"trace[946871723] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-public/; range_end:/registry/persistentvolumeclaims/kube-public0; response_count:0; response_revision:2042; }","duration":"235.229337ms","start":"2026-03-19T17:57:22.132666Z","end":"2026-03-19T17:57:22.367896Z","steps":["trace[946871723] 'agreement among raft nodes before linearized reading' (duration: 235.053452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:25.256665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.364668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/kube-proxy\" ","response":"range_response_count:1 size:450"} {"level":"warn","ts":"2026-03-19T17:57:25.2567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.395299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/kubeadm:kubeadm-certs\" ","response":"range_response_count:1 size:483"} {"level":"info","ts":"2026-03-19T17:57:25.256746Z","caller":"traceutil/trace.go:171","msg":"trace[1164680698] range","detail":"{range_begin:/registry/rolebindings/kube-system/kube-proxy; range_end:; response_count:1; response_revision:2058; }","duration":"104.494202ms","start":"2026-03-19T17:57:25.152236Z","end":"2026-03-19T17:57:25.25673Z","steps":["trace[1164680698] 'range keys from in-memory index tree' (duration: 104.220504ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:25.256778Z","caller":"traceutil/trace.go:171","msg":"trace[200095320] range","detail":"{range_begin:/registry/rolebindings/kube-system/kubeadm:kubeadm-certs; range_end:; response_count:1; response_revision:2058; }","duration":"100.496892ms","start":"2026-03-19T17:57:25.156262Z","end":"2026-03-19T17:57:25.256759Z","steps":["trace[200095320] 'range keys from in-memory index tree' (duration: 100.319347ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:26.728862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.780822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/extension-apiserver-authentication-reader\" ","response":"range_response_count:1 size:671"} {"level":"warn","ts":"2026-03-19T17:57:26.728911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.091694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-03-19T17:57:26.728968Z","caller":"traceutil/trace.go:171","msg":"trace[2139695290] range","detail":"{range_begin:/registry/roles/kube-system/extension-apiserver-authentication-reader; range_end:; response_count:1; response_revision:2061; }","duration":"241.903726ms","start":"2026-03-19T17:57:26.487021Z","end":"2026-03-19T17:57:26.728925Z","steps":["trace[2139695290] 'range keys from in-memory index tree' (duration: 241.665539ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:26.728996Z","caller":"traceutil/trace.go:171","msg":"trace[1054896931] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2061; }","duration":"150.197207ms","start":"2026-03-19T17:57:26.578767Z","end":"2026-03-19T17:57:26.728964Z","steps":["trace[1054896931] 'range keys from in-memory index tree' (duration: 149.931129ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:26.728881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.464015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kube-proxy\" ","response":"range_response_count:1 size:317"} {"level":"info","ts":"2026-03-19T17:57:26.729533Z","caller":"traceutil/trace.go:171","msg":"trace[1525812133] range","detail":"{range_begin:/registry/roles/kube-system/kube-proxy; range_end:; response_count:1; response_revision:2061; }","duration":"228.020622ms","start":"2026-03-19T17:57:26.501351Z","end":"2026-03-19T17:57:26.729371Z","steps":["trace[1525812133] 'range keys from in-memory index tree' (duration: 227.163276ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:26.942361Z","caller":"traceutil/trace.go:171","msg":"trace[1012602405] linearizableReadLoop","detail":"{readStateIndex:2144; appliedIndex:2143; }","duration":"135.59806ms","start":"2026-03-19T17:57:26.806737Z","end":"2026-03-19T17:57:26.942335Z","steps":["trace[1012602405] 'read index received' (duration: 135.360513ms)","trace[1012602405] 'applied index is now lower than readState.Index' (duration: 236.297µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:57:26.94242Z","caller":"traceutil/trace.go:171","msg":"trace[1797045048] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"203.87131ms","start":"2026-03-19T17:57:26.738508Z","end":"2026-03-19T17:57:26.94238Z","steps":["trace[1797045048] 'process raft request' (duration: 203.645413ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:26.94256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.802777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kubeadm:kubeadm-certs\" ","response":"range_response_count:1 size:339"} {"level":"info","ts":"2026-03-19T17:57:26.942659Z","caller":"traceutil/trace.go:171","msg":"trace[1385078662] range","detail":"{range_begin:/registry/roles/kube-system/kubeadm:kubeadm-certs; range_end:; response_count:1; response_revision:2062; }","duration":"135.945091ms","start":"2026-03-19T17:57:26.806693Z","end":"2026-03-19T17:57:26.942638Z","steps":["trace[1385078662] 'agreement among raft nodes before linearized reading' (duration: 135.758926ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:26.942665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.832278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-public/kubeadm:bootstrap-signer-clusterinfo\" ","response":"range_response_count:1 size:371"} {"level":"info","ts":"2026-03-19T17:57:26.942851Z","caller":"traceutil/trace.go:171","msg":"trace[1238976128] range","detail":"{range_begin:/registry/roles/kube-public/kubeadm:bootstrap-signer-clusterinfo; range_end:; response_count:1; response_revision:2062; }","duration":"132.044735ms","start":"2026-03-19T17:57:26.810698Z","end":"2026-03-19T17:57:26.942743Z","steps":["trace[1238976128] 'agreement among raft nodes before linearized reading' (duration: 131.768926ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:27.156535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.764888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:57:27.156623Z","caller":"traceutil/trace.go:171","msg":"trace[1866884829] linearizableReadLoop","detail":"{readStateIndex:2145; appliedIndex:2144; }","duration":"144.486616ms","start":"2026-03-19T17:57:27.012123Z","end":"2026-03-19T17:57:27.15661Z","steps":["trace[1866884829] 'read index received' (duration: 27.487231ms)","trace[1866884829] 'applied index is now lower than readState.Index' (duration: 116.998175ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:57:27.156705Z","caller":"traceutil/trace.go:171","msg":"trace[139591744] transaction","detail":"{read_only:false; response_revision:2063; number_of_response:1; }","duration":"207.325073ms","start":"2026-03-19T17:57:26.949359Z","end":"2026-03-19T17:57:27.156684Z","steps":["trace[139591744] 'process raft request' (duration: 90.313128ms)","trace[139591744] 'compare' (duration: 116.662855ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:57:27.156883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.233579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-public/kubeadm:bootstrap-signer-clusterinfo\" ","response":"range_response_count:1 size:371"} {"level":"info","ts":"2026-03-19T17:57:27.157034Z","caller":"traceutil/trace.go:171","msg":"trace[1578673543] range","detail":"{range_begin:/registry/roles/kube-public/kubeadm:bootstrap-signer-clusterinfo; range_end:; response_count:1; response_revision:2063; }","duration":"142.401164ms","start":"2026-03-19T17:57:27.014603Z","end":"2026-03-19T17:57:27.157004Z","steps":["trace[1578673543] 'agreement among raft nodes before linearized reading' (duration: 142.112525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:27.15693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.564853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-03-19T17:57:27.157034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.914499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kubeadm:kubeadm-certs\" ","response":"range_response_count:1 size:339"} {"level":"warn","ts":"2026-03-19T17:57:27.156975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.319914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-19T17:57:27.157214Z","caller":"traceutil/trace.go:171","msg":"trace[208688208] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2063; }","duration":"120.604253ms","start":"2026-03-19T17:57:27.036605Z","end":"2026-03-19T17:57:27.157209Z","steps":["trace[208688208] 'agreement among raft nodes before linearized reading' (duration: 120.287094ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:27.157192Z","caller":"traceutil/trace.go:171","msg":"trace[44893274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2063; }","duration":"103.861382ms","start":"2026-03-19T17:57:27.053321Z","end":"2026-03-19T17:57:27.157182Z","steps":["trace[44893274] 'agreement among raft nodes before linearized reading' (duration: 103.498651ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:27.157227Z","caller":"traceutil/trace.go:171","msg":"trace[875361172] range","detail":"{range_begin:/registry/roles/kube-system/kubeadm:kubeadm-certs; range_end:; response_count:1; response_revision:2063; }","duration":"145.155986ms","start":"2026-03-19T17:57:27.012053Z","end":"2026-03-19T17:57:27.157209Z","steps":["trace[875361172] 'agreement among raft nodes before linearized reading' (duration: 144.739114ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:27.551872Z","caller":"traceutil/trace.go:171","msg":"trace[561419269] linearizableReadLoop","detail":"{readStateIndex:2148; appliedIndex:2145; }","duration":"388.352401ms","start":"2026-03-19T17:57:27.163499Z","end":"2026-03-19T17:57:27.551851Z","steps":["trace[561419269] 'read index received' (duration: 66.789535ms)","trace[561419269] 'applied index is now lower than readState.Index' (duration: 321.561686ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:57:27.55207Z","caller":"traceutil/trace.go:171","msg":"trace[1158665207] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"389.860997ms","start":"2026-03-19T17:57:27.162171Z","end":"2026-03-19T17:57:27.552032Z","steps":["trace[1158665207] 'process raft request' (duration: 385.169637ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:27.55224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:57:27.16216Z","time spent":"389.99167ms","remote":"127.0.0.1:40868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:57:27.552246Z","caller":"traceutil/trace.go:171","msg":"trace[870485618] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"389.97115ms","start":"2026-03-19T17:57:27.162258Z","end":"2026-03-19T17:57:27.552229Z","steps":["trace[870485618] 'process raft request' (duration: 389.550287ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:27.552435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:57:27.162241Z","time spent":"390.114824ms","remote":"127.0.0.1:40868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:57:27.552583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.079883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-public/kubeadm:bootstrap-signer-clusterinfo\" ","response":"range_response_count:1 size:371"} {"level":"info","ts":"2026-03-19T17:57:27.552633Z","caller":"traceutil/trace.go:171","msg":"trace[1202348526] range","detail":"{range_begin:/registry/roles/kube-public/kubeadm:bootstrap-signer-clusterinfo; range_end:; response_count:1; response_revision:2066; }","duration":"389.154265ms","start":"2026-03-19T17:57:27.163464Z","end":"2026-03-19T17:57:27.552618Z","steps":["trace[1202348526] 'agreement among raft nodes before linearized reading' (duration: 389.043232ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:27.552675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:57:27.163453Z","time spent":"389.211567ms","remote":"127.0.0.1:40952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":394,"request content":"key:\"/registry/roles/kube-public/kubeadm:bootstrap-signer-clusterinfo\" "} {"level":"info","ts":"2026-03-19T17:57:27.554579Z","caller":"traceutil/trace.go:171","msg":"trace[1509013851] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"184.388159ms","start":"2026-03-19T17:57:27.370154Z","end":"2026-03-19T17:57:27.554542Z","steps":["trace[1509013851] 'process raft request' (duration: 184.120491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:27.575863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.620987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kubeadm:kubeadm-certs\" ","response":"range_response_count:1 size:339"} {"level":"info","ts":"2026-03-19T17:57:27.575974Z","caller":"traceutil/trace.go:171","msg":"trace[1093394324] range","detail":"{range_begin:/registry/roles/kube-system/kubeadm:kubeadm-certs; range_end:; response_count:1; response_revision:2067; }","duration":"411.781781ms","start":"2026-03-19T17:57:27.16416Z","end":"2026-03-19T17:57:27.575942Z","steps":["trace[1093394324] 'agreement among raft nodes before linearized reading' (duration: 411.619626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:27.576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.143434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:57:27.576148Z","caller":"traceutil/trace.go:171","msg":"trace[331815680] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2067; }","duration":"245.411452ms","start":"2026-03-19T17:57:27.330714Z","end":"2026-03-19T17:57:27.576126Z","steps":["trace[331815680] 'agreement among raft nodes before linearized reading' (duration: 245.121643ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:27.576045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:57:27.164136Z","time spent":"411.889125ms","remote":"127.0.0.1:40952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":362,"request content":"key:\"/registry/roles/kube-system/kubeadm:kubeadm-certs\" "} {"level":"info","ts":"2026-03-19T17:57:29.719529Z","caller":"traceutil/trace.go:171","msg":"trace[1895720393] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"159.567237ms","start":"2026-03-19T17:57:29.559933Z","end":"2026-03-19T17:57:29.7195Z","steps":["trace[1895720393] 'process raft request' (duration: 159.368521ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:29.77642Z","caller":"traceutil/trace.go:171","msg":"trace[1793712592] linearizableReadLoop","detail":"{readStateIndex:2158; appliedIndex:2156; }","duration":"182.827222ms","start":"2026-03-19T17:57:29.593571Z","end":"2026-03-19T17:57:29.776398Z","steps":["trace[1793712592] 'read index received' (duration: 125.711986ms)","trace[1793712592] 'applied index is now lower than readState.Index' (duration: 57.113606ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:57:29.776516Z","caller":"traceutil/trace.go:171","msg":"trace[1757064281] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"215.619791ms","start":"2026-03-19T17:57:29.560853Z","end":"2026-03-19T17:57:29.776473Z","steps":["trace[1757064281] 'process raft request' (duration: 215.432525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:29.776672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.964051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:57:29.776736Z","caller":"traceutil/trace.go:171","msg":"trace[544560507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2075; }","duration":"140.041883ms","start":"2026-03-19T17:57:29.636681Z","end":"2026-03-19T17:57:29.776723Z","steps":["trace[544560507] 'agreement among raft nodes before linearized reading' (duration: 139.94747ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:29.776747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.322279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-19T17:57:29.776778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.165877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-19T17:57:29.776836Z","caller":"traceutil/trace.go:171","msg":"trace[689535606] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2075; }","duration":"140.240889ms","start":"2026-03-19T17:57:29.636583Z","end":"2026-03-19T17:57:29.776824Z","steps":["trace[689535606] 'agreement among raft nodes before linearized reading' (duration: 140.076624ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:29.776839Z","caller":"traceutil/trace.go:171","msg":"trace[1516867837] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:2075; }","duration":"158.440723ms","start":"2026-03-19T17:57:29.618377Z","end":"2026-03-19T17:57:29.776817Z","steps":["trace[1516867837] 'agreement among raft nodes before linearized reading' (duration: 158.241917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:29.777063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.015107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-19T17:57:29.777109Z","caller":"traceutil/trace.go:171","msg":"trace[549134035] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:2075; }","duration":"183.568323ms","start":"2026-03-19T17:57:29.593528Z","end":"2026-03-19T17:57:29.777096Z","steps":["trace[549134035] 'agreement among raft nodes before linearized reading' (duration: 182.998476ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:30.005882Z","caller":"traceutil/trace.go:171","msg":"trace[2119254055] linearizableReadLoop","detail":"{readStateIndex:2159; appliedIndex:2158; }","duration":"223.6646ms","start":"2026-03-19T17:57:29.782193Z","end":"2026-03-19T17:57:30.005857Z","steps":["trace[2119254055] 'read index received' (duration: 223.493045ms)","trace[2119254055] 'applied index is now lower than readState.Index' (duration: 170.105µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:57:30.005944Z","caller":"traceutil/trace.go:171","msg":"trace[109037694] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"224.858786ms","start":"2026-03-19T17:57:29.78105Z","end":"2026-03-19T17:57:30.005908Z","steps":["trace[109037694] 'process raft request' (duration: 224.66404ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:30.006078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.840186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-19T17:57:30.006122Z","caller":"traceutil/trace.go:171","msg":"trace[1885930039] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:2076; }","duration":"223.944669ms","start":"2026-03-19T17:57:29.782165Z","end":"2026-03-19T17:57:30.00611Z","steps":["trace[1885930039] 'agreement among raft nodes before linearized reading' (duration: 223.829916ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:30.006491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.543867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:6 size:360369"} {"level":"info","ts":"2026-03-19T17:57:30.006567Z","caller":"traceutil/trace.go:171","msg":"trace[1294588081] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2076; }","duration":"223.670701ms","start":"2026-03-19T17:57:29.782879Z","end":"2026-03-19T17:57:30.006549Z","steps":["trace[1294588081] 'agreement among raft nodes before linearized reading' (duration: 223.278479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:30.006486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.150739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-03-19T17:57:30.006867Z","caller":"traceutil/trace.go:171","msg":"trace[701137092] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2076; }","duration":"113.600213ms","start":"2026-03-19T17:57:29.893241Z","end":"2026-03-19T17:57:30.006841Z","steps":["trace[701137092] 'agreement among raft nodes before linearized reading' (duration: 113.022396ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:30.2652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.100915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:57:30.265337Z","caller":"traceutil/trace.go:171","msg":"trace[1504529344] linearizableReadLoop","detail":"{readStateIndex:2160; appliedIndex:2159; }","duration":"231.404853ms","start":"2026-03-19T17:57:30.033917Z","end":"2026-03-19T17:57:30.265322Z","steps":["trace[1504529344] 'read index received' (duration: 92.05391ms)","trace[1504529344] 'applied index is now lower than readState.Index' (duration: 139.349272ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:57:30.265366Z","caller":"traceutil/trace.go:171","msg":"trace[934204139] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"253.894464ms","start":"2026-03-19T17:57:30.011449Z","end":"2026-03-19T17:57:30.265344Z","steps":["trace[934204139] 'process raft request' (duration: 114.571002ms)","trace[934204139] 'compare' (duration: 139.000053ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:57:30.265662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.367918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-03-19T17:57:30.265737Z","caller":"traceutil/trace.go:171","msg":"trace[500755943] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2077; }","duration":"177.484432ms","start":"2026-03-19T17:57:30.088238Z","end":"2026-03-19T17:57:30.265722Z","steps":["trace[500755943] 'agreement among raft nodes before linearized reading' (duration: 177.297586ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:30.266219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.195647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:135 size:103624"} {"level":"info","ts":"2026-03-19T17:57:30.266264Z","caller":"traceutil/trace.go:171","msg":"trace[1487777735] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:135; response_revision:2077; }","duration":"230.278799ms","start":"2026-03-19T17:57:30.035974Z","end":"2026-03-19T17:57:30.266253Z","steps":["trace[1487777735] 'agreement among raft nodes before linearized reading' (duration: 229.499296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:30.266428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.480034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:135 size:103624"} {"level":"info","ts":"2026-03-19T17:57:30.266546Z","caller":"traceutil/trace.go:171","msg":"trace[244049584] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:135; response_revision:2077; }","duration":"232.64171ms","start":"2026-03-19T17:57:30.033884Z","end":"2026-03-19T17:57:30.266526Z","steps":["trace[244049584] 'agreement among raft nodes before linearized reading' (duration: 231.567467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:30.709701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.685201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:57:30.709798Z","caller":"traceutil/trace.go:171","msg":"trace[1246184024] linearizableReadLoop","detail":"{readStateIndex:2162; appliedIndex:2161; }","duration":"367.585731ms","start":"2026-03-19T17:57:30.342198Z","end":"2026-03-19T17:57:30.709783Z","steps":["trace[1246184024] 'read index received' (duration: 58.674783ms)","trace[1246184024] 'applied index is now lower than readState.Index' (duration: 308.909568ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:57:30.709835Z","caller":"traceutil/trace.go:171","msg":"trace[1911540736] transaction","detail":"{read_only:false; response_revision:2079; number_of_response:1; }","duration":"368.615642ms","start":"2026-03-19T17:57:30.341183Z","end":"2026-03-19T17:57:30.709799Z","steps":["trace[1911540736] 'process raft request' (duration: 59.745145ms)","trace[1911540736] 'compare' (duration: 308.592658ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:57:30.709907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.710275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-03-19T17:57:30.709935Z","caller":"traceutil/trace.go:171","msg":"trace[790550668] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:2079; }","duration":"367.761726ms","start":"2026-03-19T17:57:30.342164Z","end":"2026-03-19T17:57:30.709926Z","steps":["trace[790550668] 'agreement among raft nodes before linearized reading' (duration: 367.702135ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:30.709966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.445167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-19T17:57:30.710028Z","caller":"traceutil/trace.go:171","msg":"trace[784982555] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:2079; }","duration":"366.526039ms","start":"2026-03-19T17:57:30.343488Z","end":"2026-03-19T17:57:30.710014Z","steps":["trace[784982555] 'agreement among raft nodes before linearized reading' (duration: 366.414546ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:30.710013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:57:30.341172Z","time spent":"368.733635ms","remote":"127.0.0.1:40868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:57:30.710071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:57:30.343481Z","time spent":"366.581951ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-03-19T17:57:30.709973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:57:30.342149Z","time spent":"367.815798ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-03-19T17:57:30.710535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.547369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-19T17:57:30.710641Z","caller":"traceutil/trace.go:171","msg":"trace[696166443] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2079; }","duration":"295.700413ms","start":"2026-03-19T17:57:30.414919Z","end":"2026-03-19T17:57:30.71062Z","steps":["trace[696166443] 'agreement among raft nodes before linearized reading' (duration: 295.27272ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:30.910779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.590915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:57:30.910896Z","caller":"traceutil/trace.go:171","msg":"trace[1116382721] linearizableReadLoop","detail":"{readStateIndex:2163; appliedIndex:2162; }","duration":"131.101066ms","start":"2026-03-19T17:57:30.779777Z","end":"2026-03-19T17:57:30.910878Z","steps":["trace[1116382721] 'read index received' (duration: 30.191512ms)","trace[1116382721] 'applied index is now lower than readState.Index' (duration: 100.908134ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:57:30.910978Z","caller":"traceutil/trace.go:171","msg":"trace[801042313] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"195.445668ms","start":"2026-03-19T17:57:30.71552Z","end":"2026-03-19T17:57:30.910965Z","steps":["trace[801042313] 'process raft request' (duration: 94.570975ms)","trace[801042313] 'compare' (duration: 100.334127ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:57:30.911134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.865489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-03-19T17:57:30.911342Z","caller":"traceutil/trace.go:171","msg":"trace[267717590] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:2080; }","duration":"129.118357ms","start":"2026-03-19T17:57:30.782205Z","end":"2026-03-19T17:57:30.911324Z","steps":["trace[267717590] 'agreement among raft nodes before linearized reading' (duration: 128.785167ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:30.911441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.656233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-19T17:57:30.91152Z","caller":"traceutil/trace.go:171","msg":"trace[334184878] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:2080; }","duration":"131.763276ms","start":"2026-03-19T17:57:30.779736Z","end":"2026-03-19T17:57:30.9115Z","steps":["trace[334184878] 'agreement among raft nodes before linearized reading' (duration: 131.634402ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:31.205714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.576685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:1 size:220"} {"level":"warn","ts":"2026-03-19T17:57:31.205747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.479511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:1 size:232"} {"level":"info","ts":"2026-03-19T17:57:31.205794Z","caller":"traceutil/trace.go:171","msg":"trace[1495119209] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:1; response_revision:2080; }","duration":"169.696399ms","start":"2026-03-19T17:57:31.036078Z","end":"2026-03-19T17:57:31.205774Z","steps":["trace[1495119209] 'range keys from in-memory index tree' (duration: 169.493823ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:31.205819Z","caller":"traceutil/trace.go:171","msg":"trace[1549560599] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:1; response_revision:2080; }","duration":"178.590785ms","start":"2026-03-19T17:57:31.02721Z","end":"2026-03-19T17:57:31.205801Z","steps":["trace[1549560599] 'range keys from in-memory index tree' (duration: 178.395679ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:31.371357Z","caller":"traceutil/trace.go:171","msg":"trace[370921574] linearizableReadLoop","detail":"{readStateIndex:2164; appliedIndex:2163; }","duration":"163.319739ms","start":"2026-03-19T17:57:31.208012Z","end":"2026-03-19T17:57:31.371332Z","steps":["trace[370921574] 'read index received' (duration: 163.196605ms)","trace[370921574] 'applied index is now lower than readState.Index' (duration: 121.754µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:57:31.371592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.556556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-03-19T17:57:31.371667Z","caller":"traceutil/trace.go:171","msg":"trace[263048009] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2081; }","duration":"163.641238ms","start":"2026-03-19T17:57:31.208007Z","end":"2026-03-19T17:57:31.371648Z","steps":["trace[263048009] 'agreement among raft nodes before linearized reading' (duration: 163.437392ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:31.372141Z","caller":"traceutil/trace.go:171","msg":"trace[314180392] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"172.776731ms","start":"2026-03-19T17:57:31.199337Z","end":"2026-03-19T17:57:31.372113Z","steps":["trace[314180392] 'process raft request' (duration: 171.832653ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:31.45164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.650864ms","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-03-19T17:57:31.452948Z","caller":"traceutil/trace.go:171","msg":"trace[12699568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2082; }","duration":"198.982344ms","start":"2026-03-19T17:57:31.253933Z","end":"2026-03-19T17:57:31.452915Z","steps":["trace[12699568] 'agreement among raft nodes before linearized reading' (duration: 197.583712ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:31.45302Z","caller":"traceutil/trace.go:171","msg":"trace[972526233] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"148.185517ms","start":"2026-03-19T17:57:31.304794Z","end":"2026-03-19T17:57:31.452979Z","steps":["trace[972526233] 'process raft request' (duration: 146.593229ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:31.4532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.646546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:1 size:232"} {"level":"info","ts":"2026-03-19T17:57:31.453355Z","caller":"traceutil/trace.go:171","msg":"trace[1081936103] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:1; response_revision:2082; }","duration":"179.819452ms","start":"2026-03-19T17:57:31.273472Z","end":"2026-03-19T17:57:31.453291Z","steps":["trace[1081936103] 'agreement among raft nodes before linearized reading' (duration: 179.550914ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:31.453762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.555045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:1 size:220"} {"level":"info","ts":"2026-03-19T17:57:31.453911Z","caller":"traceutil/trace.go:171","msg":"trace[932780103] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:1; response_revision:2082; }","duration":"165.650299ms","start":"2026-03-19T17:57:31.288162Z","end":"2026-03-19T17:57:31.453813Z","steps":["trace[932780103] 'agreement among raft nodes before linearized reading' (duration: 165.521785ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:31.521213Z","caller":"traceutil/trace.go:171","msg":"trace[403539166] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"145.009252ms","start":"2026-03-19T17:57:31.376154Z","end":"2026-03-19T17:57:31.521163Z","steps":["trace[403539166] 'process raft request' (duration: 144.771995ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:31.754694Z","caller":"traceutil/trace.go:171","msg":"trace[862637480] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"292.077395ms","start":"2026-03-19T17:57:31.462587Z","end":"2026-03-19T17:57:31.754664Z","steps":["trace[862637480] 'process raft request' (duration: 211.851748ms)","trace[862637480] 'compare' (duration: 80.032541ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:57:31.754662Z","caller":"traceutil/trace.go:171","msg":"trace[991112399] linearizableReadLoop","detail":"{readStateIndex:2167; appliedIndex:2166; }","duration":"233.562447ms","start":"2026-03-19T17:57:31.521061Z","end":"2026-03-19T17:57:31.754624Z","steps":["trace[991112399] 'read index received' (duration: 153.244627ms)","trace[991112399] 'applied index is now lower than readState.Index' (duration: 80.31591ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:57:31.754944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.207279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:1 size:220"} {"level":"info","ts":"2026-03-19T17:57:31.754999Z","caller":"traceutil/trace.go:171","msg":"trace[1023969866] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:1; response_revision:2084; }","duration":"293.312411ms","start":"2026-03-19T17:57:31.46167Z","end":"2026-03-19T17:57:31.754983Z","steps":["trace[1023969866] 'agreement among raft nodes before linearized reading' (duration: 293.181707ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:31.755352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.377072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:6 size:360369"} {"level":"info","ts":"2026-03-19T17:57:31.755404Z","caller":"traceutil/trace.go:171","msg":"trace[359798654] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2084; }","duration":"230.433744ms","start":"2026-03-19T17:57:31.524957Z","end":"2026-03-19T17:57:31.755391Z","steps":["trace[359798654] 'agreement among raft nodes before linearized reading' (duration: 230.115544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:31.755763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.293969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-03-19T17:57:31.755895Z","caller":"traceutil/trace.go:171","msg":"trace[1590434200] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2084; }","duration":"231.491395ms","start":"2026-03-19T17:57:31.524373Z","end":"2026-03-19T17:57:31.755865Z","steps":["trace[1590434200] 'agreement among raft nodes before linearized reading' (duration: 230.565237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:31.75579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.576813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:57:31.75618Z","caller":"traceutil/trace.go:171","msg":"trace[573327590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2084; }","duration":"119.057537ms","start":"2026-03-19T17:57:31.637109Z","end":"2026-03-19T17:57:31.756167Z","steps":["trace[573327590] 'agreement among raft nodes before linearized reading' (duration: 117.934883ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:31.957842Z","caller":"traceutil/trace.go:171","msg":"trace[1285263280] linearizableReadLoop","detail":"{readStateIndex:2169; appliedIndex:2168; }","duration":"177.331438ms","start":"2026-03-19T17:57:31.780491Z","end":"2026-03-19T17:57:31.957822Z","steps":["trace[1285263280] 'read index received' (duration: 118.683286ms)","trace[1285263280] 'applied index is now lower than readState.Index' (duration: 58.647072ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:57:31.958295Z","caller":"traceutil/trace.go:171","msg":"trace[1523469760] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"194.318405ms","start":"2026-03-19T17:57:31.763955Z","end":"2026-03-19T17:57:31.958273Z","steps":["trace[1523469760] 'process raft request' (duration: 135.319803ms)","trace[1523469760] 'compare' (duration: 58.453706ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:57:31.958345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.831183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-19T17:57:31.958405Z","caller":"traceutil/trace.go:171","msg":"trace[560960069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2086; }","duration":"177.945326ms","start":"2026-03-19T17:57:31.780443Z","end":"2026-03-19T17:57:31.958389Z","steps":["trace[560960069] 'agreement among raft nodes before linearized reading' (duration: 177.75201ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:31.958781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.941576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:135 size:103624"} {"level":"info","ts":"2026-03-19T17:57:31.958868Z","caller":"traceutil/trace.go:171","msg":"trace[881676378] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:135; response_revision:2086; }","duration":"171.05128ms","start":"2026-03-19T17:57:31.787798Z","end":"2026-03-19T17:57:31.958849Z","steps":["trace[881676378] 'agreement among raft nodes before linearized reading' (duration: 170.281477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:31.959382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.942057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:135 size:103624"} {"level":"info","ts":"2026-03-19T17:57:31.959474Z","caller":"traceutil/trace.go:171","msg":"trace[25162906] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:135; response_revision:2086; }","duration":"171.079011ms","start":"2026-03-19T17:57:31.788381Z","end":"2026-03-19T17:57:31.959459Z","steps":["trace[25162906] 'agreement among raft nodes before linearized reading' (duration: 169.998179ms)"],"step_count":1}