{"level":"warn","ts":"2026-05-09T01:31:52.238348Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T01:31:52.238459Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25: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-05-09T01:31:52.238538Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T01:31:52.238554Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-05-09T01:31:52.238577Z","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-05-09T01:31:52.239143Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-05-09T01:31:52.239225Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25: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-05-09T01:31:52.250341Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.841522ms"} {"level":"info","ts":"2026-05-09T01:31:52.295214Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-05-09T01:31:52.295306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T01:31:52.295348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-05-09T01:31:52.295356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T01:31:52.295363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-05-09T01:31:52.295392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-05-09T01:31:52.3976Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T01:31:52.400549Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T01:31:52.4027Z","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-05-09T01:31:52.405835Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T01:31:52.406058Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T01:31:52.406161Z","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-05-09T01:31:52.406197Z","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-05-09T01:31:52.406204Z","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-05-09T01:31:52.407685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-05-09T01:31:52.407817Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-05-09T01:31:52.410738Z","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-05-09T01:31:52.410828Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-05-09T01:31:52.41086Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-05-09T01:31:52.411147Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T01:31:52.411215Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T01:31:52.59631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T01:31:52.596359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T01:31:52.59639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-05-09T01:31:52.596404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-05-09T01:31:52.596412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-05-09T01:31:52.596427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-05-09T01:31:52.596438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-05-09T01:31:52.597689Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T01:31:52.603078Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T01:31:52.603229Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T01:31:52.603256Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T01:31:52.603564Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T01:31:52.603684Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T01:31:52.604925Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"info","ts":"2026-05-09T01:31:52.60602Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T01:31:52.607273Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T01:31:52.607694Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T01:31:52.607759Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T01:31:54.841591Z","caller":"traceutil/trace.go:171","msg":"trace[136900360] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"115.17219ms","start":"2026-05-09T01:31:54.726378Z","end":"2026-05-09T01:31:54.84155Z","steps":["trace[136900360] 'process raft request' (duration: 115.06188ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:31:54.84174Z","caller":"traceutil/trace.go:171","msg":"trace[695282238] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"115.33188ms","start":"2026-05-09T01:31:54.726331Z","end":"2026-05-09T01:31:54.841663Z","steps":["trace[695282238] 'process raft request' (duration: 115.03024ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:31:54.841861Z","caller":"traceutil/trace.go:171","msg":"trace[333032640] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"115.4256ms","start":"2026-05-09T01:31:54.726425Z","end":"2026-05-09T01:31:54.841851Z","steps":["trace[333032640] 'process raft request' (duration: 115.04677ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:31:54.841821Z","caller":"traceutil/trace.go:171","msg":"trace[1792352139] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"115.02279ms","start":"2026-05-09T01:31:54.726785Z","end":"2026-05-09T01:31:54.841808Z","steps":["trace[1792352139] 'process raft request' (duration: 114.7567ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:31:54.84213Z","caller":"traceutil/trace.go:171","msg":"trace[1144791558] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"115.28709ms","start":"2026-05-09T01:31:54.726824Z","end":"2026-05-09T01:31:54.842112Z","steps":["trace[1144791558] 'process raft request' (duration: 114.75037ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:31:54.842144Z","caller":"traceutil/trace.go:171","msg":"trace[389642065] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"114.53343ms","start":"2026-05-09T01:31:54.727598Z","end":"2026-05-09T01:31:54.842131Z","steps":["trace[389642065] 'process raft request' (duration: 114.02327ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:31:54.84225Z","caller":"traceutil/trace.go:171","msg":"trace[1770720314] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"116.172851ms","start":"2026-05-09T01:31:54.726065Z","end":"2026-05-09T01:31:54.842238Z","steps":["trace[1770720314] 'process raft request' (duration: 108.617694ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:31:54.842045Z","caller":"traceutil/trace.go:171","msg":"trace[413828528] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"115.52255ms","start":"2026-05-09T01:31:54.726449Z","end":"2026-05-09T01:31:54.841972Z","steps":["trace[413828528] 'process raft request' (duration: 115.06719ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:31:55.00987Z","caller":"traceutil/trace.go:171","msg":"trace[179547471] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"157.582204ms","start":"2026-05-09T01:31:54.852261Z","end":"2026-05-09T01:31:55.009843Z","steps":["trace[179547471] 'process raft request' (duration: 157.532514ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:31:55.009972Z","caller":"traceutil/trace.go:171","msg":"trace[2128300255] linearizableReadLoop","detail":"{readStateIndex:42; appliedIndex:40; }","duration":"162.283169ms","start":"2026-05-09T01:31:54.847655Z","end":"2026-05-09T01:31:55.009938Z","steps":["trace[2128300255] 'read index received' (duration: 1.889502ms)","trace[2128300255] 'applied index is now lower than readState.Index' (duration: 160.388217ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:31:55.010151Z","caller":"traceutil/trace.go:171","msg":"trace[181617351] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"163.870381ms","start":"2026-05-09T01:31:54.846249Z","end":"2026-05-09T01:31:55.010119Z","steps":["trace[181617351] 'process raft request' (duration: 107.375452ms)","trace[181617351] 'compare' (duration: 56.008398ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:31:55.010367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.69142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T01:31:55.010443Z","caller":"traceutil/trace.go:171","msg":"trace[1430333654] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:39; }","duration":"162.81856ms","start":"2026-05-09T01:31:54.847613Z","end":"2026-05-09T01:31:55.010431Z","steps":["trace[1430333654] 'agreement among raft nodes before linearized reading' (duration: 162.419339ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:31:55.340864Z","caller":"traceutil/trace.go:171","msg":"trace[820813822] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"163.458129ms","start":"2026-05-09T01:31:55.177362Z","end":"2026-05-09T01:31:55.34082Z","steps":["trace[820813822] 'process raft request' (duration: 114.056769ms)","trace[820813822] 'compare' (duration: 49.04724ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:31:55.356367Z","caller":"traceutil/trace.go:171","msg":"trace[1835025502] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"113.183848ms","start":"2026-05-09T01:31:55.243172Z","end":"2026-05-09T01:31:55.356355Z","steps":["trace[1835025502] 'process raft request' (duration: 113.133088ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:31:55.536642Z","caller":"traceutil/trace.go:171","msg":"trace[1840172383] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"176.782874ms","start":"2026-05-09T01:31:55.359829Z","end":"2026-05-09T01:31:55.536612Z","steps":["trace[1840172383] 'process raft request' (duration: 106.500911ms)","trace[1840172383] 'compare' (duration: 70.113993ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:31:55.541189Z","caller":"traceutil/trace.go:171","msg":"trace[1300070115] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"178.465776ms","start":"2026-05-09T01:31:55.362679Z","end":"2026-05-09T01:31:55.541145Z","steps":["trace[1300070115] 'process raft request' (duration: 178.253186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:31:55.994916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.486314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-09T01:31:55.995168Z","caller":"traceutil/trace.go:171","msg":"trace[778609263] linearizableReadLoop","detail":"{readStateIndex:70; appliedIndex:68; }","duration":"331.778645ms","start":"2026-05-09T01:31:55.663355Z","end":"2026-05-09T01:31:55.995133Z","steps":["trace[778609263] 'read index received' (duration: 48.510371ms)","trace[778609263] 'applied index is now lower than readState.Index' (duration: 283.265144ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:31:55.995245Z","caller":"traceutil/trace.go:171","msg":"trace[1137661359] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"332.892686ms","start":"2026-05-09T01:31:55.662334Z","end":"2026-05-09T01:31:55.995227Z","steps":["trace[1137661359] 'process raft request' (duration: 136.608582ms)","trace[1137661359] 'compare' (duration: 195.327644ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:31:55.995299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:31:55.662317Z","time spent":"332.959396ms","remote":"127.0.0.1:46012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":565,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T01:31:55.995411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.035935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T01:31:55.995465Z","caller":"traceutil/trace.go:171","msg":"trace[37767099] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:67; }","duration":"332.121385ms","start":"2026-05-09T01:31:55.663327Z","end":"2026-05-09T01:31:55.995448Z","steps":["trace[37767099] 'agreement among raft nodes before linearized reading' (duration: 331.931175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:31:55.995518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:31:55.663313Z","time spent":"332.194595ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"info","ts":"2026-05-09T01:31:55.995654Z","caller":"traceutil/trace.go:171","msg":"trace[1030738418] transaction","detail":"{read_only:false; response_revision:68; number_of_response:1; }","duration":"270.529111ms","start":"2026-05-09T01:31:55.725116Z","end":"2026-05-09T01:31:55.995645Z","steps":["trace[1030738418] 'process raft request' (duration: 269.94992ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:31:55.995692Z","caller":"traceutil/trace.go:171","msg":"trace[1459572000] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"279.719481ms","start":"2026-05-09T01:31:55.715966Z","end":"2026-05-09T01:31:55.995686Z","steps":["trace[1459572000] 'process raft request' (duration: 279.03997ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:01.316596Z","caller":"traceutil/trace.go:171","msg":"trace[1172592185] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:340; }","duration":"351.685229ms","start":"2026-05-09T01:32:00.964883Z","end":"2026-05-09T01:32:01.316568Z","steps":["trace[1172592185] 'read index received' (duration: 351.462828ms)","trace[1172592185] 'applied index is now lower than readState.Index' (duration: 220.861µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:32:01.316744Z","caller":"traceutil/trace.go:171","msg":"trace[1205837132] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"382.641021ms","start":"2026-05-09T01:32:00.934022Z","end":"2026-05-09T01:32:01.316663Z","steps":["trace[1205837132] 'process raft request' (duration: 382.383239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:01.316805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.89704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-09T01:32:01.316844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.333912ms","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-05-09T01:32:01.316877Z","caller":"traceutil/trace.go:171","msg":"trace[99825680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:330; }","duration":"352.00583ms","start":"2026-05-09T01:32:00.964853Z","end":"2026-05-09T01:32:01.316858Z","steps":["trace[99825680] 'agreement among raft nodes before linearized reading' (duration: 351.86025ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:01.316899Z","caller":"traceutil/trace.go:171","msg":"trace[744320173] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:330; }","duration":"276.390242ms","start":"2026-05-09T01:32:01.040493Z","end":"2026-05-09T01:32:01.316884Z","steps":["trace[744320173] 'agreement among raft nodes before linearized reading' (duration: 276.280152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:01.316932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:00.964841Z","time spent":"352.07652ms","remote":"127.0.0.1:45822","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":"warn","ts":"2026-05-09T01:32:01.316932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:00.934004Z","time spent":"382.803571ms","remote":"127.0.0.1:45746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T01:32:01.317005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.522723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T01:32:01.317142Z","caller":"traceutil/trace.go:171","msg":"trace[1594535673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:330; }","duration":"238.685774ms","start":"2026-05-09T01:32:01.078393Z","end":"2026-05-09T01:32:01.317079Z","steps":["trace[1594535673] 'agreement among raft nodes before linearized reading' (duration: 238.461324ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:06.580021Z","caller":"traceutil/trace.go:171","msg":"trace[101012677] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:413; }","duration":"244.262186ms","start":"2026-05-09T01:32:06.335728Z","end":"2026-05-09T01:32:06.57999Z","steps":["trace[101012677] 'read index received' (duration: 244.026285ms)","trace[101012677] 'applied index is now lower than readState.Index' (duration: 234.701µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:32:06.580051Z","caller":"traceutil/trace.go:171","msg":"trace[699862770] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"275.304097ms","start":"2026-05-09T01:32:06.304727Z","end":"2026-05-09T01:32:06.580031Z","steps":["trace[699862770] 'process raft request' (duration: 275.092516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:06.580243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.494817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:32:06.580397Z","caller":"traceutil/trace.go:171","msg":"trace[108523741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:402; }","duration":"244.685917ms","start":"2026-05-09T01:32:06.335693Z","end":"2026-05-09T01:32:06.580379Z","steps":["trace[108523741] 'agreement among raft nodes before linearized reading' (duration: 244.408246ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:11.584907Z","caller":"traceutil/trace.go:171","msg":"trace[327085948] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"115.906585ms","start":"2026-05-09T01:32:11.468975Z","end":"2026-05-09T01:32:11.584882Z","steps":["trace[327085948] 'process raft request' (duration: 112.174181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:18.672344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187762502s","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-05-09T01:32:18.672438Z","caller":"traceutil/trace.go:171","msg":"trace[1896768374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:462; }","duration":"1.187881132s","start":"2026-05-09T01:32:17.484534Z","end":"2026-05-09T01:32:18.672415Z","steps":["trace[1896768374] 'range keys from in-memory index tree' (duration: 1.187669962s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:18.672489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:17.484522Z","time spent":"1.187955122s","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T01:32:18.672734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15590679s","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-05-09T01:32:18.672788Z","caller":"traceutil/trace.go:171","msg":"trace[1440915827] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:462; }","duration":"1.15599128s","start":"2026-05-09T01:32:17.51678Z","end":"2026-05-09T01:32:18.672772Z","steps":["trace[1440915827] 'range keys from in-memory index tree' (duration: 1.15579671s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:18.672828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:17.51676Z","time spent":"1.15605634s","remote":"127.0.0.1:45822","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-05-09T01:32:18.673031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044485102s","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-05-09T01:32:18.67308Z","caller":"traceutil/trace.go:171","msg":"trace[1433737411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:462; }","duration":"1.044560562s","start":"2026-05-09T01:32:17.628507Z","end":"2026-05-09T01:32:18.673068Z","steps":["trace[1433737411] 'range keys from in-memory index tree' (duration: 1.044417511s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:18.673118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:17.628492Z","time spent":"1.044615612s","remote":"127.0.0.1:45822","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":"warn","ts":"2026-05-09T01:32:18.673318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.420015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-09T01:32:18.673368Z","caller":"traceutil/trace.go:171","msg":"trace[969287860] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:462; }","duration":"578.493305ms","start":"2026-05-09T01:32:18.094862Z","end":"2026-05-09T01:32:18.673355Z","steps":["trace[969287860] 'range keys from in-memory index tree' (duration: 578.336165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:18.673406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:18.094848Z","time spent":"578.547515ms","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T01:32:18.673587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.49145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:32:18.673631Z","caller":"traceutil/trace.go:171","msg":"trace[476707640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"337.58018ms","start":"2026-05-09T01:32:18.336039Z","end":"2026-05-09T01:32:18.673619Z","steps":["trace[476707640] 'range keys from in-memory index tree' (duration: 337.4242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:18.673668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:18.336018Z","time spent":"337.63979ms","remote":"127.0.0.1:45566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T01:32:18.919312Z","caller":"traceutil/trace.go:171","msg":"trace[1961814702] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"239.975204ms","start":"2026-05-09T01:32:18.679317Z","end":"2026-05-09T01:32:18.919292Z","steps":["trace[1961814702] 'process raft request' (duration: 239.920214ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:18.919365Z","caller":"traceutil/trace.go:171","msg":"trace[1709255962] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"240.098714ms","start":"2026-05-09T01:32:18.679238Z","end":"2026-05-09T01:32:18.919337Z","steps":["trace[1709255962] 'process raft request' (duration: 239.879954ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:18.919496Z","caller":"traceutil/trace.go:171","msg":"trace[760767736] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"240.101094ms","start":"2026-05-09T01:32:18.679368Z","end":"2026-05-09T01:32:18.919469Z","steps":["trace[760767736] 'process raft request' (duration: 239.894884ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:19.377855Z","caller":"traceutil/trace.go:171","msg":"trace[381316669] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"137.692875ms","start":"2026-05-09T01:32:19.240118Z","end":"2026-05-09T01:32:19.377811Z","steps":["trace[381316669] 'process raft request' (duration: 137.574175ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:23.322135Z","caller":"traceutil/trace.go:171","msg":"trace[725765428] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"113.40721ms","start":"2026-05-09T01:32:23.208664Z","end":"2026-05-09T01:32:23.322072Z","steps":["trace[725765428] 'read index received' (duration: 112.370119ms)","trace[725765428] 'applied index is now lower than readState.Index' (duration: 1.035561ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:32:23.322154Z","caller":"traceutil/trace.go:171","msg":"trace[42606104] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"115.065981ms","start":"2026-05-09T01:32:23.207052Z","end":"2026-05-09T01:32:23.322118Z","steps":["trace[42606104] 'process raft request' (duration: 113.92971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:23.322367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.66756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-869df985b8\" ","response":"range_response_count:1 size:4309"} {"level":"info","ts":"2026-05-09T01:32:23.322527Z","caller":"traceutil/trace.go:171","msg":"trace[1047145688] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-869df985b8; range_end:; response_count:1; response_revision:500; }","duration":"113.87515ms","start":"2026-05-09T01:32:23.208629Z","end":"2026-05-09T01:32:23.322504Z","steps":["trace[1047145688] 'agreement among raft nodes before linearized reading' (duration: 113.57489ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:23.334971Z","caller":"traceutil/trace.go:171","msg":"trace[614894249] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"113.39062ms","start":"2026-05-09T01:32:23.221549Z","end":"2026-05-09T01:32:23.334939Z","steps":["trace[614894249] 'process raft request' (duration: 113.059749ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:23.335674Z","caller":"traceutil/trace.go:171","msg":"trace[768180579] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"114.02165ms","start":"2026-05-09T01:32:23.221629Z","end":"2026-05-09T01:32:23.335651Z","steps":["trace[768180579] 'process raft request' (duration: 113.36582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:23.335799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.145221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-05-09T01:32:23.336075Z","caller":"traceutil/trace.go:171","msg":"trace[1289306609] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:502; }","duration":"125.309042ms","start":"2026-05-09T01:32:23.210541Z","end":"2026-05-09T01:32:23.33585Z","steps":["trace[1289306609] 'agreement among raft nodes before linearized reading' (duration: 125.115711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:23.826443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.847801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-05-09T01:32:23.826514Z","caller":"traceutil/trace.go:171","msg":"trace[1258823691] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:507; }","duration":"330.967081ms","start":"2026-05-09T01:32:23.495533Z","end":"2026-05-09T01:32:23.8265Z","steps":["trace[1258823691] 'range keys from in-memory index tree' (duration: 330.63578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:23.826544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:23.495512Z","time spent":"331.025441ms","remote":"127.0.0.1:45746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":561,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-05-09T01:32:23.826714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.63081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-05-09T01:32:23.826741Z","caller":"traceutil/trace.go:171","msg":"trace[719569220] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:507; }","duration":"330.69352ms","start":"2026-05-09T01:32:23.49604Z","end":"2026-05-09T01:32:23.826733Z","steps":["trace[719569220] 'range keys from in-memory index tree' (duration: 330.54237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:23.826758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:23.496019Z","time spent":"330.73479ms","remote":"127.0.0.1:45746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":543,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-05-09T01:32:23.826997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.055297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-05-09T01:32:23.827044Z","caller":"traceutil/trace.go:171","msg":"trace[1332356041] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:507; }","duration":"285.095867ms","start":"2026-05-09T01:32:23.541933Z","end":"2026-05-09T01:32:23.827029Z","steps":["trace[1332356041] 'range keys from in-memory index tree' (duration: 284.833736ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:25.361113Z","caller":"traceutil/trace.go:171","msg":"trace[364044116] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"178.520963ms","start":"2026-05-09T01:32:25.182563Z","end":"2026-05-09T01:32:25.361084Z","steps":["trace[364044116] 'process raft request' (duration: 178.360903ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:25.369299Z","caller":"traceutil/trace.go:171","msg":"trace[2115204272] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"183.908508ms","start":"2026-05-09T01:32:25.185367Z","end":"2026-05-09T01:32:25.369275Z","steps":["trace[2115204272] 'process raft request' (duration: 183.083897ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:25.369438Z","caller":"traceutil/trace.go:171","msg":"trace[1519822264] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"183.983318ms","start":"2026-05-09T01:32:25.185446Z","end":"2026-05-09T01:32:25.369429Z","steps":["trace[1519822264] 'process raft request' (duration: 183.109687ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:25.369511Z","caller":"traceutil/trace.go:171","msg":"trace[890280960] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"171.655195ms","start":"2026-05-09T01:32:25.19785Z","end":"2026-05-09T01:32:25.369505Z","steps":["trace[890280960] 'process raft request' (duration: 170.737964ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:25.369603Z","caller":"traceutil/trace.go:171","msg":"trace[1642330621] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"171.635785ms","start":"2026-05-09T01:32:25.197961Z","end":"2026-05-09T01:32:25.369597Z","steps":["trace[1642330621] 'process raft request' (duration: 170.650085ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:25.36968Z","caller":"traceutil/trace.go:171","msg":"trace[1562620305] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"171.619685ms","start":"2026-05-09T01:32:25.198049Z","end":"2026-05-09T01:32:25.369669Z","steps":["trace[1562620305] 'process raft request' (duration: 170.586495ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:25.369796Z","caller":"traceutil/trace.go:171","msg":"trace[1867943661] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"169.757264ms","start":"2026-05-09T01:32:25.200032Z","end":"2026-05-09T01:32:25.369789Z","steps":["trace[1867943661] 'process raft request' (duration: 168.638194ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:25.369865Z","caller":"traceutil/trace.go:171","msg":"trace[1967892847] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"104.51623ms","start":"2026-05-09T01:32:25.265344Z","end":"2026-05-09T01:32:25.36986Z","steps":["trace[1967892847] 'process raft request' (duration: 103.5446ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:25.369917Z","caller":"traceutil/trace.go:171","msg":"trace[959361083] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"160.181394ms","start":"2026-05-09T01:32:25.20973Z","end":"2026-05-09T01:32:25.369911Z","steps":["trace[959361083] 'process raft request' (duration: 158.982574ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:25.369992Z","caller":"traceutil/trace.go:171","msg":"trace[1717223132] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"135.306451ms","start":"2026-05-09T01:32:25.234679Z","end":"2026-05-09T01:32:25.369986Z","steps":["trace[1717223132] 'process raft request' (duration: 134.13066ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:25.370067Z","caller":"traceutil/trace.go:171","msg":"trace[556372441] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"112.384609ms","start":"2026-05-09T01:32:25.257675Z","end":"2026-05-09T01:32:25.37006Z","steps":["trace[556372441] 'process raft request' (duration: 111.174398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:26.798585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315971611s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T01:32:26.798906Z","caller":"traceutil/trace.go:171","msg":"trace[432349523] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.316987261s","start":"2026-05-09T01:32:25.48189Z","end":"2026-05-09T01:32:26.798877Z","steps":["trace[432349523] 'process raft request' (duration: 1.316880331s)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:26.798962Z","caller":"traceutil/trace.go:171","msg":"trace[1829029338] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.319774444s","start":"2026-05-09T01:32:25.479158Z","end":"2026-05-09T01:32:26.798933Z","steps":["trace[1829029338] 'compare' (duration: 1.3154299s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:26.798994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:25.481864Z","time spent":"1.317083611s","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T01:32:26.799098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:25.479124Z","time spent":"1.319901744s","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T01:32:26.804608Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.283887668s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T01:32:26.805619Z","caller":"traceutil/trace.go:171","msg":"trace[2111212753] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"1.2850591s","start":"2026-05-09T01:32:25.520531Z","end":"2026-05-09T01:32:26.80559Z","steps":["trace[2111212753] 'process raft request' (duration: 1.2849377s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:26.805767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:25.520498Z","time spent":"1.285189841s","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T01:32:26.828358Z","caller":"traceutil/trace.go:171","msg":"trace[717348443] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:565; }","duration":"461.933236ms","start":"2026-05-09T01:32:26.366404Z","end":"2026-05-09T01:32:26.828337Z","steps":["trace[717348443] 'read index received' (duration: 439.217543ms)","trace[717348443] 'applied index is now lower than readState.Index' (duration: 22.714903ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:32:26.828592Z","caller":"traceutil/trace.go:171","msg":"trace[477031704] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"1.292622568s","start":"2026-05-09T01:32:25.535906Z","end":"2026-05-09T01:32:26.828529Z","steps":["trace[477031704] 'process raft request' (duration: 1.292246638s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:26.828508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.084796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T01:32:26.828861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:25.53587Z","time spent":"1.292847418s","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:32:26.829045Z","caller":"traceutil/trace.go:171","msg":"trace[1546198181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:548; }","duration":"462.549376ms","start":"2026-05-09T01:32:26.366345Z","end":"2026-05-09T01:32:26.828895Z","steps":["trace[1546198181] 'agreement among raft nodes before linearized reading' (duration: 462.094916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:26.829335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:26.366324Z","time spent":"462.963707ms","remote":"127.0.0.1:45566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T01:32:27.040136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.01647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T01:32:27.040951Z","caller":"traceutil/trace.go:171","msg":"trace[575668746] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"164.140228ms","start":"2026-05-09T01:32:26.876199Z","end":"2026-05-09T01:32:27.040339Z","steps":["trace[575668746] 'process raft request' (duration: 28.798978ms)","trace[575668746] 'compare' (duration: 134.86999ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:32:27.076607Z","caller":"traceutil/trace.go:171","msg":"trace[108932563] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"182.136116ms","start":"2026-05-09T01:32:26.894443Z","end":"2026-05-09T01:32:27.076579Z","steps":["trace[108932563] 'process raft request' (duration: 181.902406ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:27.076789Z","caller":"traceutil/trace.go:171","msg":"trace[1267372860] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:570; }","duration":"114.834351ms","start":"2026-05-09T01:32:26.961843Z","end":"2026-05-09T01:32:27.076678Z","steps":["trace[1267372860] 'read index received' (duration: 114.1595ms)","trace[1267372860] 'applied index is now lower than readState.Index' (duration: 673.041µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:32:27.07698Z","caller":"traceutil/trace.go:171","msg":"trace[512194270] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"178.512612ms","start":"2026-05-09T01:32:26.898381Z","end":"2026-05-09T01:32:27.076893Z","steps":["trace[512194270] 'process raft request' (duration: 178.117692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:27.077218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.872391ms","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-05-09T01:32:27.077282Z","caller":"traceutil/trace.go:171","msg":"trace[57550098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:555; }","duration":"106.019031ms","start":"2026-05-09T01:32:26.97125Z","end":"2026-05-09T01:32:27.077269Z","steps":["trace[57550098] 'agreement among raft nodes before linearized reading' (duration: 105.764221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:27.077577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.712852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-09T01:32:27.077601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.253202ms","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-05-09T01:32:27.077713Z","caller":"traceutil/trace.go:171","msg":"trace[581508916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"106.364072ms","start":"2026-05-09T01:32:26.971338Z","end":"2026-05-09T01:32:27.077702Z","steps":["trace[581508916] 'agreement among raft nodes before linearized reading' (duration: 106.183052ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:27.077772Z","caller":"traceutil/trace.go:171","msg":"trace[107663704] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"187.922831ms","start":"2026-05-09T01:32:26.888644Z","end":"2026-05-09T01:32:27.076567Z","steps":["trace[107663704] 'process raft request' (duration: 187.5175ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:27.077633Z","caller":"traceutil/trace.go:171","msg":"trace[3558005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"115.841922ms","start":"2026-05-09T01:32:26.961778Z","end":"2026-05-09T01:32:27.07762Z","steps":["trace[3558005] 'agreement among raft nodes before linearized reading' (duration: 115.401081ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:27.996794Z","caller":"traceutil/trace.go:171","msg":"trace[915116801] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"415.432681ms","start":"2026-05-09T01:32:27.581329Z","end":"2026-05-09T01:32:27.996761Z","steps":["trace[915116801] 'process raft request' (duration: 415.173581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:27.997034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:27.581305Z","time spent":"415.591931ms","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98689,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:32:28.142648Z","caller":"traceutil/trace.go:171","msg":"trace[1026237891] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"551.251412ms","start":"2026-05-09T01:32:27.591321Z","end":"2026-05-09T01:32:28.142573Z","steps":["trace[1026237891] 'process raft request' (duration: 531.532993ms)","trace[1026237891] 'compare' (duration: 19.004277ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:32:28.142899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:27.591298Z","time spent":"551.454102ms","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:32:28.144448Z","caller":"traceutil/trace.go:171","msg":"trace[1458229664] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"447.26178ms","start":"2026-05-09T01:32:27.697147Z","end":"2026-05-09T01:32:28.144409Z","steps":["trace[1458229664] 'process raft request' (duration: 446.586989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:28.14462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:27.697116Z","time spent":"447.41733ms","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T01:32:28.144711Z","caller":"traceutil/trace.go:171","msg":"trace[1605936494] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"124.85874ms","start":"2026-05-09T01:32:28.019837Z","end":"2026-05-09T01:32:28.144696Z","steps":["trace[1605936494] 'process raft request' (duration: 124.66923ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:28.634488Z","caller":"traceutil/trace.go:171","msg":"trace[1633701800] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"220.794631ms","start":"2026-05-09T01:32:28.413668Z","end":"2026-05-09T01:32:28.634463Z","steps":["trace[1633701800] 'process raft request' (duration: 220.592821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:29.546655Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422387519548598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T01:32:29.634614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:29.247579Z","time spent":"387.027062ms","remote":"127.0.0.1:40544","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-09T01:32:29.784494Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.152471788s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T01:32:29.78559Z","caller":"traceutil/trace.go:171","msg":"trace[2059138631] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.309453128s","start":"2026-05-09T01:32:28.476103Z","end":"2026-05-09T01:32:29.785556Z","steps":["trace[2059138631] 'process raft request' (duration: 1.309367828s)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:29.785688Z","caller":"traceutil/trace.go:171","msg":"trace[1116282838] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"1.341250969s","start":"2026-05-09T01:32:28.444395Z","end":"2026-05-09T01:32:29.785646Z","steps":["trace[1116282838] 'process raft request' (duration: 1.340561009s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:29.785768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:28.476072Z","time spent":"1.309618068s","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T01:32:29.785911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:28.444365Z","time spent":"1.341392369s","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T01:32:30.635303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.238269ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T01:32:30.635555Z","caller":"traceutil/trace.go:171","msg":"trace[1879188651] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"773.838763ms","start":"2026-05-09T01:32:29.861702Z","end":"2026-05-09T01:32:30.635541Z","steps":["trace[1879188651] 'process raft request' (duration: 773.785403ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:30.635543Z","caller":"traceutil/trace.go:171","msg":"trace[794141898] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.016832265s","start":"2026-05-09T01:32:29.618678Z","end":"2026-05-09T01:32:30.63551Z","steps":["trace[794141898] 'process raft request' (duration: 1.016738995s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:30.635626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:29.861681Z","time spent":"773.910833ms","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T01:32:30.635647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:29.618648Z","time spent":"1.016951595s","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:32:30.635789Z","caller":"traceutil/trace.go:171","msg":"trace[2133333486] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.984636666s","start":"2026-05-09T01:32:28.651089Z","end":"2026-05-09T01:32:30.635725Z","steps":["trace[2133333486] 'process raft request' (duration: 1.796819497s)","trace[2133333486] 'compare' (duration: 186.684898ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:32:30.63578Z","caller":"traceutil/trace.go:171","msg":"trace[707654378] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:605; }","duration":"1.589732666s","start":"2026-05-09T01:32:29.045825Z","end":"2026-05-09T01:32:30.635557Z","steps":["trace[707654378] 'read index received' (duration: 738.9393ms)","trace[707654378] 'applied index is now lower than readState.Index' (duration: 850.779206ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:32:30.637173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.544433452s","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-05-09T01:32:30.63724Z","caller":"traceutil/trace.go:171","msg":"trace[1148509530] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"1.544558462s","start":"2026-05-09T01:32:29.092663Z","end":"2026-05-09T01:32:30.637221Z","steps":["trace[1148509530] 'agreement among raft nodes before linearized reading' (duration: 1.544383532s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:30.637286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:29.092636Z","time spent":"1.544631782s","remote":"127.0.0.1:45822","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-05-09T01:32:30.637519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.591699578s","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-05-09T01:32:30.6376Z","caller":"traceutil/trace.go:171","msg":"trace[1686582433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"1.591812888s","start":"2026-05-09T01:32:29.045756Z","end":"2026-05-09T01:32:30.637569Z","steps":["trace[1686582433] 'agreement among raft nodes before linearized reading' (duration: 1.590064207s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:30.637636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:29.045723Z","time spent":"1.591903718s","remote":"127.0.0.1:45822","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-05-09T01:32:30.637889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.300759209s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:32:30.637935Z","caller":"traceutil/trace.go:171","msg":"trace[1050025205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"1.300863309s","start":"2026-05-09T01:32:29.33706Z","end":"2026-05-09T01:32:30.637924Z","steps":["trace[1050025205] 'agreement among raft nodes before linearized reading' (duration: 1.300736868s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:30.637979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:29.337032Z","time spent":"1.300927289s","remote":"127.0.0.1:45566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T01:32:30.638586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.061884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"warn","ts":"2026-05-09T01:32:30.638613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.542920341s","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-05-09T01:32:30.638679Z","caller":"traceutil/trace.go:171","msg":"trace[1242190535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"1.543008351s","start":"2026-05-09T01:32:29.095658Z","end":"2026-05-09T01:32:30.638666Z","steps":["trace[1242190535] 'agreement among raft nodes before linearized reading' (duration: 1.542906991s)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:30.638704Z","caller":"traceutil/trace.go:171","msg":"trace[381003119] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:591; }","duration":"817.210724ms","start":"2026-05-09T01:32:29.821444Z","end":"2026-05-09T01:32:30.638654Z","steps":["trace[381003119] 'agreement among raft nodes before linearized reading' (duration: 816.873954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:30.63871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:29.095646Z","time spent":"1.543057081s","remote":"127.0.0.1:45822","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":"warn","ts":"2026-05-09T01:32:30.638782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:29.82142Z","time spent":"817.342394ms","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29593,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-05-09T01:32:30.638589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.542708981s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-09T01:32:30.639012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:28.651056Z","time spent":"1.984819037s","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20851,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:32:30.63916Z","caller":"traceutil/trace.go:171","msg":"trace[571821482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"1.543273081s","start":"2026-05-09T01:32:29.095774Z","end":"2026-05-09T01:32:30.639047Z","steps":["trace[571821482] 'agreement among raft nodes before linearized reading' (duration: 1.542107861s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:30.639331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:29.095767Z","time spent":"1.543542662s","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-09T01:32:30.909564Z","caller":"traceutil/trace.go:171","msg":"trace[916946051] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"263.162942ms","start":"2026-05-09T01:32:30.646353Z","end":"2026-05-09T01:32:30.909516Z","steps":["trace[916946051] 'process raft request' (duration: 223.188213ms)","trace[916946051] 'compare' (duration: 39.733028ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:32:30.909926Z","caller":"traceutil/trace.go:171","msg":"trace[971903423] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:610; }","duration":"262.041142ms","start":"2026-05-09T01:32:30.647851Z","end":"2026-05-09T01:32:30.909892Z","steps":["trace[971903423] 'read index received' (duration: 19.938599ms)","trace[971903423] 'applied index is now lower than readState.Index' (duration: 242.101203ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:32:30.909942Z","caller":"traceutil/trace.go:171","msg":"trace[1434145245] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"262.498202ms","start":"2026-05-09T01:32:30.647418Z","end":"2026-05-09T01:32:30.909916Z","steps":["trace[1434145245] 'process raft request' (duration: 262.382602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:30.910284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.386872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:32:30.910502Z","caller":"traceutil/trace.go:171","msg":"trace[809943871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"262.650322ms","start":"2026-05-09T01:32:30.647823Z","end":"2026-05-09T01:32:30.910473Z","steps":["trace[809943871] 'agreement among raft nodes before linearized reading' (duration: 262.323002ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:30.910397Z","caller":"traceutil/trace.go:171","msg":"trace[703233324] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"263.482463ms","start":"2026-05-09T01:32:30.646386Z","end":"2026-05-09T01:32:30.909869Z","steps":["trace[703233324] 'process raft request' (duration: 263.093202ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:30.922832Z","caller":"traceutil/trace.go:171","msg":"trace[978901009] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"245.148054ms","start":"2026-05-09T01:32:30.677651Z","end":"2026-05-09T01:32:30.922799Z","steps":["trace[978901009] 'process raft request' (duration: 244.661784ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:30.923025Z","caller":"traceutil/trace.go:171","msg":"trace[1098785262] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"141.772566ms","start":"2026-05-09T01:32:30.781182Z","end":"2026-05-09T01:32:30.922954Z","steps":["trace[1098785262] 'process raft request' (duration: 141.537195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:30.923161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.076169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-05-09T01:32:30.923249Z","caller":"traceutil/trace.go:171","msg":"trace[184298914] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:597; }","duration":"271.220759ms","start":"2026-05-09T01:32:30.652008Z","end":"2026-05-09T01:32:30.923228Z","steps":["trace[184298914] 'agreement among raft nodes before linearized reading' (duration: 270.925039ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:32.900683Z","caller":"traceutil/trace.go:171","msg":"trace[746192339] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"185.916767ms","start":"2026-05-09T01:32:32.714742Z","end":"2026-05-09T01:32:32.900659Z","steps":["trace[746192339] 'read index received' (duration: 185.780747ms)","trace[746192339] 'applied index is now lower than readState.Index' (duration: 135.13µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:32:32.9008Z","caller":"traceutil/trace.go:171","msg":"trace[1947067840] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"226.346485ms","start":"2026-05-09T01:32:32.674446Z","end":"2026-05-09T01:32:32.900792Z","steps":["trace[1947067840] 'process raft request' (duration: 226.074435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:32.900927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.156277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:32:32.900994Z","caller":"traceutil/trace.go:171","msg":"trace[391958129] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:603; }","duration":"186.262408ms","start":"2026-05-09T01:32:32.714719Z","end":"2026-05-09T01:32:32.900981Z","steps":["trace[391958129] 'agreement among raft nodes before linearized reading' (duration: 186.151947ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:33.001363Z","caller":"traceutil/trace.go:171","msg":"trace[1911028579] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"100.578226ms","start":"2026-05-09T01:32:32.900759Z","end":"2026-05-09T01:32:33.001337Z","steps":["trace[1911028579] 'read index received' (duration: 96.220183ms)","trace[1911028579] 'applied index is now lower than readState.Index' (duration: 4.356983ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:32:33.001704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.778044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T01:32:33.001702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.105196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:32:33.001753Z","caller":"traceutil/trace.go:171","msg":"trace[1637237807] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:604; }","duration":"286.859784ms","start":"2026-05-09T01:32:32.714881Z","end":"2026-05-09T01:32:33.001741Z","steps":["trace[1637237807] 'agreement among raft nodes before linearized reading' (duration: 286.752204ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:33.001781Z","caller":"traceutil/trace.go:171","msg":"trace[1833475740] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:604; }","duration":"121.189216ms","start":"2026-05-09T01:32:32.880566Z","end":"2026-05-09T01:32:33.001755Z","steps":["trace[1833475740] 'agreement among raft nodes before linearized reading' (duration: 121.041426ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:33.001908Z","caller":"traceutil/trace.go:171","msg":"trace[2110542339] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"147.04533ms","start":"2026-05-09T01:32:32.854855Z","end":"2026-05-09T01:32:33.0019Z","steps":["trace[2110542339] 'process raft request' (duration: 142.674756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:33.002016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.935906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:32:33.002062Z","caller":"traceutil/trace.go:171","msg":"trace[457068611] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:604; }","duration":"122.026376ms","start":"2026-05-09T01:32:32.880024Z","end":"2026-05-09T01:32:33.002051Z","steps":["trace[457068611] 'agreement among raft nodes before linearized reading' (duration: 121.961696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:33.0021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.438802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:32:33.00213Z","caller":"traceutil/trace.go:171","msg":"trace[500701989] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:604; }","duration":"106.524102ms","start":"2026-05-09T01:32:32.895598Z","end":"2026-05-09T01:32:33.002122Z","steps":["trace[500701989] 'agreement among raft nodes before linearized reading' (duration: 106.450612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:33.002267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.198867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T01:32:33.002292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.066003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:32:33.002306Z","caller":"traceutil/trace.go:171","msg":"trace[2094875735] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:604; }","duration":"122.299177ms","start":"2026-05-09T01:32:32.879997Z","end":"2026-05-09T01:32:33.002296Z","steps":["trace[2094875735] 'agreement among raft nodes before linearized reading' (duration: 122.229237ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:33.00233Z","caller":"traceutil/trace.go:171","msg":"trace[1857248048] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:604; }","duration":"107.110413ms","start":"2026-05-09T01:32:32.895205Z","end":"2026-05-09T01:32:33.002316Z","steps":["trace[1857248048] 'agreement among raft nodes before linearized reading' (duration: 107.025863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:33.002455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.255803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:32:33.00249Z","caller":"traceutil/trace.go:171","msg":"trace[92065669] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:604; }","duration":"107.308353ms","start":"2026-05-09T01:32:32.895173Z","end":"2026-05-09T01:32:33.002481Z","steps":["trace[92065669] 'agreement among raft nodes before linearized reading' (duration: 107.253833ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:34.831244Z","caller":"traceutil/trace.go:171","msg":"trace[2142235991] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"390.497122ms","start":"2026-05-09T01:32:34.440709Z","end":"2026-05-09T01:32:34.831207Z","steps":["trace[2142235991] 'process raft request' (duration: 382.423705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:34.831431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:34.44067Z","time spent":"390.662812ms","remote":"127.0.0.1:45648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036189,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T01:32:35.301811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.641636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T01:32:35.30195Z","caller":"traceutil/trace.go:171","msg":"trace[1026394382] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"202.678473ms","start":"2026-05-09T01:32:35.099251Z","end":"2026-05-09T01:32:35.301929Z","steps":["trace[1026394382] 'read index received' (duration: 70.803227ms)","trace[1026394382] 'applied index is now lower than readState.Index' (duration: 131.873966ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:32:35.302015Z","caller":"traceutil/trace.go:171","msg":"trace[1963839081] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"210.15773ms","start":"2026-05-09T01:32:35.0918Z","end":"2026-05-09T01:32:35.301958Z","steps":["trace[1963839081] 'process raft request' (duration: 78.269624ms)","trace[1963839081] 'compare' (duration: 131.500156ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:32:35.302134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.399972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9c9qg\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-05-09T01:32:35.302168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.912184ms","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-05-09T01:32:35.302219Z","caller":"traceutil/trace.go:171","msg":"trace[1617499809] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9c9qg; range_end:; response_count:1; response_revision:619; }","duration":"137.496472ms","start":"2026-05-09T01:32:35.164705Z","end":"2026-05-09T01:32:35.302201Z","steps":["trace[1617499809] 'agreement among raft nodes before linearized reading' (duration: 137.341281ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:35.302221Z","caller":"traceutil/trace.go:171","msg":"trace[1296810915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:619; }","duration":"202.966824ms","start":"2026-05-09T01:32:35.099241Z","end":"2026-05-09T01:32:35.302207Z","steps":["trace[1296810915] 'agreement among raft nodes before linearized reading' (duration: 202.737593ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:36.179843Z","caller":"traceutil/trace.go:171","msg":"trace[162715873] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"872.597782ms","start":"2026-05-09T01:32:35.30722Z","end":"2026-05-09T01:32:36.179818Z","steps":["trace[162715873] 'process raft request' (duration: 870.95791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:36.180046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:35.307213Z","time spent":"872.718392ms","remote":"127.0.0.1:45648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:32:36.22158Z","caller":"traceutil/trace.go:171","msg":"trace[442210243] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:639; }","duration":"887.925656ms","start":"2026-05-09T01:32:35.333625Z","end":"2026-05-09T01:32:36.221551Z","steps":["trace[442210243] 'read index received' (duration: 33.586072ms)","trace[442210243] 'applied index is now lower than readState.Index' (duration: 854.338864ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:32:36.221734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.072656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:32:36.221769Z","caller":"traceutil/trace.go:171","msg":"trace[138938564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"888.169216ms","start":"2026-05-09T01:32:35.33359Z","end":"2026-05-09T01:32:36.221759Z","steps":["trace[138938564] 'agreement among raft nodes before linearized reading' (duration: 888.066876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:36.2218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:35.333579Z","time spent":"888.213596ms","remote":"127.0.0.1:45554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T01:32:36.222136Z","caller":"traceutil/trace.go:171","msg":"trace[746057252] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"828.204789ms","start":"2026-05-09T01:32:35.393856Z","end":"2026-05-09T01:32:36.222061Z","steps":["trace[746057252] 'process raft request' (duration: 827.639849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:36.22237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:35.393846Z","time spent":"828.399899ms","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:32:36.222668Z","caller":"traceutil/trace.go:171","msg":"trace[1837882668] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"910.882227ms","start":"2026-05-09T01:32:35.311761Z","end":"2026-05-09T01:32:36.222643Z","steps":["trace[1837882668] 'process raft request' (duration: 909.594597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:36.222761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:35.311751Z","time spent":"910.973497ms","remote":"127.0.0.1:45738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T01:32:38.337135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000551583s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-09T01:32:38.337248Z","caller":"traceutil/trace.go:171","msg":"trace[1821074493] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000727533s","start":"2026-05-09T01:32:36.336493Z","end":"2026-05-09T01:32:38.337221Z","steps":["trace[1821074493] 'agreement among raft nodes before linearized reading' (duration: 2.000589463s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:38.337307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:36.336467Z","time spent":"2.000828783s","remote":"127.0.0.1:45566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T01:32:38.832756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.53382575s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T01:32:38.832902Z","caller":"traceutil/trace.go:171","msg":"trace[2110256783] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"2.496331574s","start":"2026-05-09T01:32:36.336551Z","end":"2026-05-09T01:32:38.832882Z","steps":["trace[2110256783] 'read index received' (duration: 45.04µs)","trace[2110256783] 'applied index is now lower than readState.Index' (duration: 2.496283594s)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:32:38.833092Z","caller":"traceutil/trace.go:171","msg":"trace[229464769] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"2.582784587s","start":"2026-05-09T01:32:36.250268Z","end":"2026-05-09T01:32:38.833052Z","steps":["trace[229464769] 'process raft request' (duration: 46.417654ms)","trace[229464769] 'compare' (duration: 2.5332258s)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:32:38.83324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:36.250226Z","time spent":"2.582944617s","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T01:32:38.833376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.746351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:32:38.833459Z","caller":"traceutil/trace.go:171","msg":"trace[528673224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"495.887311ms","start":"2026-05-09T01:32:38.337553Z","end":"2026-05-09T01:32:38.83344Z","steps":["trace[528673224] 'agreement among raft nodes before linearized reading' (duration: 495.767351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:38.833472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.527515642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-09T01:32:38.833491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:38.337523Z","time spent":"495.961281ms","remote":"127.0.0.1:45554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T01:32:38.833521Z","caller":"traceutil/trace.go:171","msg":"trace[106220994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"1.527628402s","start":"2026-05-09T01:32:37.305879Z","end":"2026-05-09T01:32:38.833507Z","steps":["trace[106220994] 'agreement among raft nodes before linearized reading' (duration: 1.527367022s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:38.833564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:37.305854Z","time spent":"1.527701872s","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T01:32:38.8337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587241178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-09T01:32:38.833748Z","caller":"traceutil/trace.go:171","msg":"trace[41902409] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:624; }","duration":"1.587289838s","start":"2026-05-09T01:32:37.246447Z","end":"2026-05-09T01:32:38.833737Z","steps":["trace[41902409] 'agreement among raft nodes before linearized reading' (duration: 1.587073268s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:38.833793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:37.246377Z","time spent":"1.587406448s","remote":"127.0.0.1:45586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.25\" "} {"level":"warn","ts":"2026-05-09T01:32:38.833799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.46200076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-09T01:32:38.833879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.769794442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-09T01:32:38.833911Z","caller":"traceutil/trace.go:171","msg":"trace[584929944] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"1.769873953s","start":"2026-05-09T01:32:37.064029Z","end":"2026-05-09T01:32:38.833903Z","steps":["trace[584929944] 'agreement among raft nodes before linearized reading' (duration: 1.769730113s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:38.833938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:37.064009Z","time spent":"1.769922343s","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T01:32:38.833891Z","caller":"traceutil/trace.go:171","msg":"trace[379749711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"1.4621496s","start":"2026-05-09T01:32:37.371728Z","end":"2026-05-09T01:32:38.833878Z","steps":["trace[379749711] 'agreement among raft nodes before linearized reading' (duration: 1.46180775s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:38.833996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:37.371647Z","time spent":"1.46234033s","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T01:32:38.834015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.917465633s","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-05-09T01:32:38.834063Z","caller":"traceutil/trace.go:171","msg":"trace[86421838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"1.917513483s","start":"2026-05-09T01:32:36.916538Z","end":"2026-05-09T01:32:38.834052Z","steps":["trace[86421838] 'agreement among raft nodes before linearized reading' (duration: 1.916668413s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:38.833425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.79716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:32:38.834172Z","caller":"traceutil/trace.go:171","msg":"trace[160453236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"253.60729ms","start":"2026-05-09T01:32:38.580553Z","end":"2026-05-09T01:32:38.83416Z","steps":["trace[160453236] 'agreement among raft nodes before linearized reading' (duration: 252.66016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:38.834126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:36.916475Z","time spent":"1.917643823s","remote":"127.0.0.1:45822","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-05-09T01:32:40.634184Z","caller":"traceutil/trace.go:171","msg":"trace[807968102] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"297.421662ms","start":"2026-05-09T01:32:40.336732Z","end":"2026-05-09T01:32:40.634153Z","steps":["trace[807968102] 'process raft request' (duration: 297.121262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:40.634512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.304109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:32:40.634601Z","caller":"traceutil/trace.go:171","msg":"trace[293020824] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:649; }","duration":"295.45763ms","start":"2026-05-09T01:32:40.339124Z","end":"2026-05-09T01:32:40.634582Z","steps":["trace[293020824] 'agreement among raft nodes before linearized reading' (duration: 295.213179ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:40.634047Z","caller":"traceutil/trace.go:171","msg":"trace[1913598604] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"294.877959ms","start":"2026-05-09T01:32:40.339142Z","end":"2026-05-09T01:32:40.63402Z","steps":["trace[1913598604] 'read index received' (duration: 294.772979ms)","trace[1913598604] 'applied index is now lower than readState.Index' (duration: 103.47µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:32:40.645011Z","caller":"traceutil/trace.go:171","msg":"trace[1246290916] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"305.47234ms","start":"2026-05-09T01:32:40.339518Z","end":"2026-05-09T01:32:40.644991Z","steps":["trace[1246290916] 'process raft request' (duration: 305.26563ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:40.645107Z","caller":"traceutil/trace.go:171","msg":"trace[400640548] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"299.570135ms","start":"2026-05-09T01:32:40.345458Z","end":"2026-05-09T01:32:40.645028Z","steps":["trace[400640548] 'process raft request' (duration: 299.529305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:40.645414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:40.339498Z","time spent":"305.57229ms","remote":"127.0.0.1:45822","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-05-09T01:32:40.645252Z","caller":"traceutil/trace.go:171","msg":"trace[580076402] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"301.532647ms","start":"2026-05-09T01:32:40.343699Z","end":"2026-05-09T01:32:40.645232Z","steps":["trace[580076402] 'process raft request' (duration: 301.238067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:40.645632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:40.343683Z","time spent":"301.845797ms","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T01:32:40.650167Z","caller":"traceutil/trace.go:171","msg":"trace[896082528] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"280.950507ms","start":"2026-05-09T01:32:40.369193Z","end":"2026-05-09T01:32:40.650143Z","steps":["trace[896082528] 'process raft request' (duration: 279.045525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:40.650276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.079404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:32:40.650371Z","caller":"traceutil/trace.go:171","msg":"trace[673874391] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:655; }","duration":"311.189484ms","start":"2026-05-09T01:32:40.339153Z","end":"2026-05-09T01:32:40.650342Z","steps":["trace[673874391] 'agreement among raft nodes before linearized reading' (duration: 311.043924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:40.650431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:40.339145Z","time spent":"311.272025ms","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-05-09T01:32:40.650589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.986582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T01:32:40.650593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.188602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T01:32:40.65061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.9443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:32:40.650644Z","caller":"traceutil/trace.go:171","msg":"trace[992098825] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:655; }","duration":"307.063232ms","start":"2026-05-09T01:32:40.343567Z","end":"2026-05-09T01:32:40.65063Z","steps":["trace[992098825] 'agreement among raft nodes before linearized reading' (duration: 306.919192ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:40.650653Z","caller":"traceutil/trace.go:171","msg":"trace[1072555319] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:655; }","duration":"307.263272ms","start":"2026-05-09T01:32:40.343369Z","end":"2026-05-09T01:32:40.650633Z","steps":["trace[1072555319] 'agreement among raft nodes before linearized reading' (duration: 307.189452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:40.650685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:40.343542Z","time spent":"307.127892ms","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-05-09T01:32:40.650696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:40.343362Z","time spent":"307.325922ms","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-05-09T01:32:40.650659Z","caller":"traceutil/trace.go:171","msg":"trace[1616424692] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:655; }","duration":"137.05109ms","start":"2026-05-09T01:32:40.513595Z","end":"2026-05-09T01:32:40.650646Z","steps":["trace[1616424692] 'agreement among raft nodes before linearized reading' (duration: 136.92619ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:32:46.630534Z","caller":"traceutil/trace.go:171","msg":"trace[1274305758] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"152.875993ms","start":"2026-05-09T01:32:46.477621Z","end":"2026-05-09T01:32:46.630497Z","steps":["trace[1274305758] 'process raft request' (duration: 152.718903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:55.50959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422387519549061,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T01:32:56.010772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422387519549061,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T01:32:56.372942Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.636524094s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T01:32:56.373447Z","caller":"traceutil/trace.go:171","msg":"trace[1816397658] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"1.637134874s","start":"2026-05-09T01:32:54.736269Z","end":"2026-05-09T01:32:56.373404Z","steps":["trace[1816397658] 'process raft request' (duration: 1.636979754s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:56.373603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:54.736245Z","time spent":"1.637276725s","remote":"127.0.0.1:45822","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-05-09T01:32:56.593254Z","caller":"traceutil/trace.go:171","msg":"trace[449573290] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:819; }","duration":"1.583777993s","start":"2026-05-09T01:32:55.009444Z","end":"2026-05-09T01:32:56.593222Z","steps":["trace[449573290] 'read index received' (duration: 1.363818347s)","trace[449573290] 'applied index is now lower than readState.Index' (duration: 219.958336ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:32:56.593334Z","caller":"traceutil/trace.go:171","msg":"trace[1253373785] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"1.289850649s","start":"2026-05-09T01:32:55.303437Z","end":"2026-05-09T01:32:56.593288Z","steps":["trace[1253373785] 'process raft request' (duration: 1.289615618s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:56.593853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257340309s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:32:56.593914Z","caller":"traceutil/trace.go:171","msg":"trace[107114226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:796; }","duration":"1.257495889s","start":"2026-05-09T01:32:55.336399Z","end":"2026-05-09T01:32:56.593895Z","steps":["trace[107114226] 'agreement among raft nodes before linearized reading' (duration: 1.257370059s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:56.593954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:55.336374Z","time spent":"1.257571239s","remote":"127.0.0.1:45566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T01:32:56.59397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.524593029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T01:32:56.594025Z","caller":"traceutil/trace.go:171","msg":"trace[1224319891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"1.524762509s","start":"2026-05-09T01:32:55.069246Z","end":"2026-05-09T01:32:56.594009Z","steps":["trace[1224319891] 'agreement among raft nodes before linearized reading' (duration: 1.524481039s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:56.594122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:55.069222Z","time spent":"1.524886599s","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T01:32:56.594308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.538819162s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T01:32:56.594481Z","caller":"traceutil/trace.go:171","msg":"trace[1156440014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"1.539021192s","start":"2026-05-09T01:32:55.055427Z","end":"2026-05-09T01:32:56.594448Z","steps":["trace[1156440014] 'agreement among raft nodes before linearized reading' (duration: 1.538413362s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:56.594585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:55.055417Z","time spent":"1.539149692s","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T01:32:56.594647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.585159424s","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-05-09T01:32:56.594764Z","caller":"traceutil/trace.go:171","msg":"trace[1299444288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:796; }","duration":"1.585367034s","start":"2026-05-09T01:32:55.009368Z","end":"2026-05-09T01:32:56.594735Z","steps":["trace[1299444288] 'agreement among raft nodes before linearized reading' (duration: 1.583956864s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:56.594824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:55.009343Z","time spent":"1.585469694s","remote":"127.0.0.1:45822","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-05-09T01:32:56.594849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.54728812s","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-05-09T01:32:56.594969Z","caller":"traceutil/trace.go:171","msg":"trace[309810601] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:796; }","duration":"1.547451101s","start":"2026-05-09T01:32:55.047494Z","end":"2026-05-09T01:32:56.594945Z","steps":["trace[309810601] 'agreement among raft nodes before linearized reading' (duration: 1.54618418s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:32:56.595027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:55.047484Z","time spent":"1.547533982s","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T01:32:56.595238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:32:55.303393Z","time spent":"1.29099408s","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T01:33:10.624609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.596882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-fcq85\" ","response":"range_response_count:1 size:5676"} {"level":"info","ts":"2026-05-09T01:33:10.624712Z","caller":"traceutil/trace.go:171","msg":"trace[985671581] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-fcq85; range_end:; response_count:1; response_revision:959; }","duration":"120.774032ms","start":"2026-05-09T01:33:10.503915Z","end":"2026-05-09T01:33:10.624689Z","steps":["trace[985671581] 'range keys from in-memory index tree' (duration: 120.354472ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:33:10.809503Z","caller":"traceutil/trace.go:171","msg":"trace[16172764] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"122.066444ms","start":"2026-05-09T01:33:10.687414Z","end":"2026-05-09T01:33:10.80948Z","steps":["trace[16172764] 'read index received' (duration: 121.906054ms)","trace[16172764] 'applied index is now lower than readState.Index' (duration: 159.66µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:33:10.809548Z","caller":"traceutil/trace.go:171","msg":"trace[1221419158] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"173.146251ms","start":"2026-05-09T01:33:10.636382Z","end":"2026-05-09T01:33:10.809529Z","steps":["trace[1221419158] 'process raft request' (duration: 172.960031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:33:10.809746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.310214ms","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-05-09T01:33:10.809817Z","caller":"traceutil/trace.go:171","msg":"trace[841399478] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:960; }","duration":"122.442224ms","start":"2026-05-09T01:33:10.687359Z","end":"2026-05-09T01:33:10.809801Z","steps":["trace[841399478] 'agreement among raft nodes before linearized reading' (duration: 122.215944ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:34:11.247516Z","caller":"traceutil/trace.go:171","msg":"trace[829664276] linearizableReadLoop","detail":"{readStateIndex:1214; appliedIndex:1213; }","duration":"110.557153ms","start":"2026-05-09T01:34:11.136937Z","end":"2026-05-09T01:34:11.247494Z","steps":["trace[829664276] 'read index received' (duration: 110.397263ms)","trace[829664276] 'applied index is now lower than readState.Index' (duration: 159.16µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:34:11.247651Z","caller":"traceutil/trace.go:171","msg":"trace[691341711] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"116.282488ms","start":"2026-05-09T01:34:11.131347Z","end":"2026-05-09T01:34:11.247629Z","steps":["trace[691341711] 'process raft request' (duration: 116.007728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:34:11.247788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.252566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-09T01:34:11.24782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.655843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T01:34:11.24785Z","caller":"traceutil/trace.go:171","msg":"trace[1812759868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1173; }","duration":"104.341406ms","start":"2026-05-09T01:34:11.143496Z","end":"2026-05-09T01:34:11.247838Z","steps":["trace[1812759868] 'agreement among raft nodes before linearized reading' (duration: 104.178516ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:34:11.247876Z","caller":"traceutil/trace.go:171","msg":"trace[2077342712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1173; }","duration":"110.745853ms","start":"2026-05-09T01:34:11.137114Z","end":"2026-05-09T01:34:11.24786Z","steps":["trace[2077342712] 'agreement among raft nodes before linearized reading' (duration: 110.546903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:34:11.247998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.063813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-09T01:34:11.248022Z","caller":"traceutil/trace.go:171","msg":"trace[643635097] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1173; }","duration":"111.128913ms","start":"2026-05-09T01:34:11.136887Z","end":"2026-05-09T01:34:11.248016Z","steps":["trace[643635097] 'agreement among raft nodes before linearized reading' (duration: 111.050533ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:34:13.043767Z","caller":"traceutil/trace.go:171","msg":"trace[763850372] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"175.406802ms","start":"2026-05-09T01:34:12.868336Z","end":"2026-05-09T01:34:13.043743Z","steps":["trace[763850372] 'process raft request' (duration: 175.242172ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:34:21.573026Z","caller":"traceutil/trace.go:171","msg":"trace[423808455] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"139.895849ms","start":"2026-05-09T01:34:21.433096Z","end":"2026-05-09T01:34:21.572991Z","steps":["trace[423808455] 'process raft request' (duration: 69.055474ms)","trace[423808455] 'compare' (duration: 70.473665ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:34:47.777458Z","caller":"traceutil/trace.go:171","msg":"trace[1496707000] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1380; }","duration":"261.279131ms","start":"2026-05-09T01:34:47.516155Z","end":"2026-05-09T01:34:47.777434Z","steps":["trace[1496707000] 'read index received' (duration: 261.174641ms)","trace[1496707000] 'applied index is now lower than readState.Index' (duration: 101.54µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:34:47.777949Z","caller":"traceutil/trace.go:171","msg":"trace[2008454228] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"339.470873ms","start":"2026-05-09T01:34:47.438449Z","end":"2026-05-09T01:34:47.77792Z","steps":["trace[2008454228] 'process raft request' (duration: 338.642612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:34:47.777978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.816201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-05-09T01:34:47.778024Z","caller":"traceutil/trace.go:171","msg":"trace[1845933048] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1331; }","duration":"261.903561ms","start":"2026-05-09T01:34:47.516109Z","end":"2026-05-09T01:34:47.778012Z","steps":["trace[1845933048] 'agreement among raft nodes before linearized reading' (duration: 261.809091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:34:47.778079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:34:47.438442Z","time spent":"339.565903ms","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:34:47.7783Z","caller":"traceutil/trace.go:171","msg":"trace[862624548] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"338.845592ms","start":"2026-05-09T01:34:47.43944Z","end":"2026-05-09T01:34:47.778286Z","steps":["trace[862624548] 'process raft request' (duration: 337.945402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:34:47.778607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:34:47.439434Z","time spent":"339.106742ms","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T01:34:47.961478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.265128ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T01:34:47.961744Z","caller":"traceutil/trace.go:171","msg":"trace[123433647] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"289.605517ms","start":"2026-05-09T01:34:47.672115Z","end":"2026-05-09T01:34:47.961721Z","steps":["trace[123433647] 'process raft request' (duration: 172.930059ms)","trace[123433647] 'compare' (duration: 115.949928ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:34:47.9619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.596867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-09T01:34:47.961943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.541717ms","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-05-09T01:34:47.961955Z","caller":"traceutil/trace.go:171","msg":"trace[1075310002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1332; }","duration":"344.677927ms","start":"2026-05-09T01:34:47.617264Z","end":"2026-05-09T01:34:47.961941Z","steps":["trace[1075310002] 'agreement among raft nodes before linearized reading' (duration: 344.502277ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:34:47.961978Z","caller":"traceutil/trace.go:171","msg":"trace[994747396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1332; }","duration":"356.622177ms","start":"2026-05-09T01:34:47.605346Z","end":"2026-05-09T01:34:47.961968Z","steps":["trace[994747396] 'agreement among raft nodes before linearized reading' (duration: 356.499497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:34:47.961992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:34:47.617249Z","time spent":"344.734927ms","remote":"127.0.0.1:45822","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":"warn","ts":"2026-05-09T01:34:47.962022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:34:47.605323Z","time spent":"356.691867ms","remote":"127.0.0.1:45822","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":"info","ts":"2026-05-09T01:34:47.961936Z","caller":"traceutil/trace.go:171","msg":"trace[146772033] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1382; }","duration":"184.007809ms","start":"2026-05-09T01:34:47.777628Z","end":"2026-05-09T01:34:47.961636Z","steps":["trace[146772033] 'read index received' (duration: 67.277541ms)","trace[146772033] 'applied index is now lower than readState.Index' (duration: 116.728098ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:34:47.961918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.292015ms","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-05-09T01:34:47.96278Z","caller":"traceutil/trace.go:171","msg":"trace[582185030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1332; }","duration":"224.179516ms","start":"2026-05-09T01:34:47.738559Z","end":"2026-05-09T01:34:47.962739Z","steps":["trace[582185030] 'agreement among raft nodes before linearized reading' (duration: 223.133405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:34:47.962935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.660488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-09T01:34:47.963014Z","caller":"traceutil/trace.go:171","msg":"trace[1204523071] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1332; }","duration":"181.750808ms","start":"2026-05-09T01:34:47.781238Z","end":"2026-05-09T01:34:47.962988Z","steps":["trace[1204523071] 'agreement among raft nodes before linearized reading' (duration: 181.616508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:34:47.963098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.530296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:34:47.963158Z","caller":"traceutil/trace.go:171","msg":"trace[956991947] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1332; }","duration":"104.638926ms","start":"2026-05-09T01:34:47.858504Z","end":"2026-05-09T01:34:47.963143Z","steps":["trace[956991947] 'agreement among raft nodes before linearized reading' (duration: 104.530266ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:34:48.215925Z","caller":"traceutil/trace.go:171","msg":"trace[1037392985] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"249.21195ms","start":"2026-05-09T01:34:47.966686Z","end":"2026-05-09T01:34:48.215898Z","steps":["trace[1037392985] 'process raft request' (duration: 153.770981ms)","trace[1037392985] 'compare' (duration: 95.254858ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:34:48.216823Z","caller":"traceutil/trace.go:171","msg":"trace[1400225803] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1383; }","duration":"248.818209ms","start":"2026-05-09T01:34:47.967969Z","end":"2026-05-09T01:34:48.216787Z","steps":["trace[1400225803] 'read index received' (duration: 71.298955ms)","trace[1400225803] 'applied index is now lower than readState.Index' (duration: 177.516924ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:34:48.217022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.026369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:34:48.217122Z","caller":"traceutil/trace.go:171","msg":"trace[679291117] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1335; }","duration":"249.161269ms","start":"2026-05-09T01:34:47.967944Z","end":"2026-05-09T01:34:48.217105Z","steps":["trace[679291117] 'agreement among raft nodes before linearized reading' (duration: 249.011759ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:34:48.217074Z","caller":"traceutil/trace.go:171","msg":"trace[1950180510] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"249.65959ms","start":"2026-05-09T01:34:47.9674Z","end":"2026-05-09T01:34:48.21706Z","steps":["trace[1950180510] 'process raft request' (duration: 248.456089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:34:48.43913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.358613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:34:48.439862Z","caller":"traceutil/trace.go:171","msg":"trace[1250509451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1335; }","duration":"103.115884ms","start":"2026-05-09T01:34:48.336689Z","end":"2026-05-09T01:34:48.439805Z","steps":["trace[1250509451] 'range keys from in-memory index tree' (duration: 102.272583ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:34:50.162637Z","caller":"traceutil/trace.go:171","msg":"trace[651084630] linearizableReadLoop","detail":"{readStateIndex:1387; appliedIndex:1386; }","duration":"380.227899ms","start":"2026-05-09T01:34:49.782386Z","end":"2026-05-09T01:34:50.162614Z","steps":["trace[651084630] 'read index received' (duration: 380.007969ms)","trace[651084630] 'applied index is now lower than readState.Index' (duration: 218.96µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:34:50.162905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.496181ms","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-05-09T01:34:50.162982Z","caller":"traceutil/trace.go:171","msg":"trace[10911860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1336; }","duration":"380.609791ms","start":"2026-05-09T01:34:49.782354Z","end":"2026-05-09T01:34:50.162964Z","steps":["trace[10911860] 'agreement among raft nodes before linearized reading' (duration: 380.342949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:34:50.163022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:34:49.782338Z","time spent":"380.676131ms","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-09T01:34:50.163321Z","caller":"traceutil/trace.go:171","msg":"trace[32317657] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"801.117367ms","start":"2026-05-09T01:34:49.362189Z","end":"2026-05-09T01:34:50.163306Z","steps":["trace[32317657] 'process raft request' (duration: 800.242395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:34:50.163495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:34:49.362162Z","time spent":"801.223347ms","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:34:50.208221Z","caller":"traceutil/trace.go:171","msg":"trace[559789725] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"240.734851ms","start":"2026-05-09T01:34:49.967453Z","end":"2026-05-09T01:34:50.208188Z","steps":["trace[559789725] 'process raft request' (duration: 240.559861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:34:50.20844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.625821ms","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-05-09T01:34:50.208486Z","caller":"traceutil/trace.go:171","msg":"trace[621970362] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1337; }","duration":"424.694581ms","start":"2026-05-09T01:34:49.783777Z","end":"2026-05-09T01:34:50.208472Z","steps":["trace[621970362] 'agreement among raft nodes before linearized reading' (duration: 424.370601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:34:50.208521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:34:49.783767Z","time spent":"424.746461ms","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T01:34:50.208426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.911082ms","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-05-09T01:34:50.208868Z","caller":"traceutil/trace.go:171","msg":"trace[1393710153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1337; }","duration":"165.381352ms","start":"2026-05-09T01:34:50.043437Z","end":"2026-05-09T01:34:50.208818Z","steps":["trace[1393710153] 'agreement among raft nodes before linearized reading' (duration: 164.758422ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:34:50.328254Z","caller":"traceutil/trace.go:171","msg":"trace[552827455] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1388; }","duration":"107.40318ms","start":"2026-05-09T01:34:50.220825Z","end":"2026-05-09T01:34:50.328228Z","steps":["trace[552827455] 'read index received' (duration: 38.318956ms)","trace[552827455] 'applied index is now lower than readState.Index' (duration: 69.083044ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:34:50.328293Z","caller":"traceutil/trace.go:171","msg":"trace[1752674168] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"114.957626ms","start":"2026-05-09T01:34:50.213304Z","end":"2026-05-09T01:34:50.328262Z","steps":["trace[1752674168] 'process raft request' (duration: 114.877505ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:34:50.328303Z","caller":"traceutil/trace.go:171","msg":"trace[2126609169] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"115.535108ms","start":"2026-05-09T01:34:50.212724Z","end":"2026-05-09T01:34:50.32826Z","steps":["trace[2126609169] 'process raft request' (duration: 84.753269ms)","trace[2126609169] 'compare' (duration: 30.569247ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:34:50.328658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.71017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-09T01:34:50.328744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.5917ms","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-05-09T01:34:50.328922Z","caller":"traceutil/trace.go:171","msg":"trace[1743853390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1340; }","duration":"107.76426ms","start":"2026-05-09T01:34:50.2211Z","end":"2026-05-09T01:34:50.328864Z","steps":["trace[1743853390] 'agreement among raft nodes before linearized reading' (duration: 107.43892ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:34:50.328974Z","caller":"traceutil/trace.go:171","msg":"trace[574996437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1340; }","duration":"107.92117ms","start":"2026-05-09T01:34:50.220782Z","end":"2026-05-09T01:34:50.328703Z","steps":["trace[574996437] 'agreement among raft nodes before linearized reading' (duration: 107.58636ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:34:53.494043Z","caller":"traceutil/trace.go:171","msg":"trace[1665065442] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"108.23675ms","start":"2026-05-09T01:34:53.38578Z","end":"2026-05-09T01:34:53.494017Z","steps":["trace[1665065442] 'process raft request' (duration: 108.09013ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:34:58.61215Z","caller":"traceutil/trace.go:171","msg":"trace[690207079] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"118.190518ms","start":"2026-05-09T01:34:58.493934Z","end":"2026-05-09T01:34:58.612124Z","steps":["trace[690207079] 'process raft request' (duration: 117.549927ms)"],"step_count":1}