{"level":"warn","ts":"2026-03-02T18:27:11.643502Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T18:27:11.643626Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-02T18:27:11.643789Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T18:27:11.643836Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-03-02T18:27:11.643871Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-02T18:27:11.644953Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-03-02T18:27:11.645136Z","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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-02T18:27:11.650127Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.666582ms"} {"level":"info","ts":"2026-03-02T18:27:11.656411Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-03-02T18:27:11.656773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-03-02T18:27:11.656818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-03-02T18:27:11.656878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-02T18:27:11.656889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-03-02T18:27:11.657016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-03-02T18:27:11.664837Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-02T18:27:11.66593Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-02T18:27:11.66689Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-02T18:27:11.673255Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-02T18:27:11.678953Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-02T18:27:11.679049Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-02T18:27:11.679062Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-02T18:27:11.679291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-03-02T18:27:11.679297Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-02T18:27:11.679423Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-03-02T18:27:11.679634Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-02T18:27:11.679745Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-03-02T18:27:11.679773Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-03-02T18:27:11.680466Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-02T18:27:11.680502Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-02T18:27:12.257439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-03-02T18:27:12.257511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-03-02T18:27:12.257531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-03-02T18:27:12.257546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-03-02T18:27:12.257552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-03-02T18:27:12.257563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-03-02T18:27:12.257575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-03-02T18:27:12.258655Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T18:27:12.259949Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T18:27:12.260058Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T18:27:12.260089Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T18:27:12.260123Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-03-02T18:27:12.260144Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T18:27:12.260444Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T18:27:12.261788Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-02T18:27:12.263159Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-02T18:27:12.263187Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-02T18:27:12.266155Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"warn","ts":"2026-03-02T18:27:18.504623Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743063442041789,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-02T18:27:18.557236Z","caller":"traceutil/trace.go:171","msg":"trace[1337942281] transaction","detail":"{read_only:false; number_of_response:0; response_revision:316; }","duration":"552.693967ms","start":"2026-03-02T18:27:18.004505Z","end":"2026-03-02T18:27:18.557199Z","steps":["trace[1337942281] 'process raft request' (duration: 552.593852ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:18.557852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:18.004488Z","time spent":"552.814665ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T18:27:18.561014Z","caller":"traceutil/trace.go:171","msg":"trace[962364171] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:325; }","duration":"556.407217ms","start":"2026-03-02T18:27:18.004582Z","end":"2026-03-02T18:27:18.560989Z","steps":["trace[962364171] 'read index received' (duration: 552.642155ms)","trace[962364171] 'applied index is now lower than readState.Index' (duration: 3.764072ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:27:18.561052Z","caller":"traceutil/trace.go:171","msg":"trace[34601501] transaction","detail":"{read_only:false; number_of_response:0; response_revision:316; }","duration":"553.669226ms","start":"2026-03-02T18:27:18.007367Z","end":"2026-03-02T18:27:18.561036Z","steps":["trace[34601501] 'process raft request' (duration: 553.57787ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:18.561614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.95136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-03-02T18:27:18.5617Z","caller":"traceutil/trace.go:171","msg":"trace[863097206] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:316; }","duration":"489.104939ms","start":"2026-03-02T18:27:18.072574Z","end":"2026-03-02T18:27:18.561679Z","steps":["trace[863097206] 'agreement among raft nodes before linearized reading' (duration: 488.916188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:18.561784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:18.072518Z","time spent":"489.249417ms","remote":"127.0.0.1:57276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"info","ts":"2026-03-02T18:27:18.561095Z","caller":"traceutil/trace.go:171","msg":"trace[2088769633] transaction","detail":"{read_only:false; number_of_response:0; response_revision:316; }","duration":"556.518313ms","start":"2026-03-02T18:27:18.004568Z","end":"2026-03-02T18:27:18.561087Z","steps":["trace[2088769633] 'process raft request' (duration: 556.30312ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:27:18.561132Z","caller":"traceutil/trace.go:171","msg":"trace[1634445243] transaction","detail":"{read_only:false; number_of_response:0; response_revision:316; }","duration":"552.648085ms","start":"2026-03-02T18:27:18.008478Z","end":"2026-03-02T18:27:18.561126Z","steps":["trace[1634445243] 'process raft request' (duration: 552.487556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:18.562031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:18.004538Z","time spent":"557.389764ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T18:27:18.562155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:18.008467Z","time spent":"553.564559ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T18:27:18.561279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.63699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-03-02T18:27:18.562243Z","caller":"traceutil/trace.go:171","msg":"trace[503398591] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:316; }","duration":"557.704153ms","start":"2026-03-02T18:27:18.004524Z","end":"2026-03-02T18:27:18.562228Z","steps":["trace[503398591] 'agreement among raft nodes before linearized reading' (duration: 556.642541ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:18.562289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:18.004507Z","time spent":"557.770267ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5919,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-03-02T18:27:18.561554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.727703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-02T18:27:18.562406Z","caller":"traceutil/trace.go:171","msg":"trace[1111598042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:316; }","duration":"232.608245ms","start":"2026-03-02T18:27:18.329758Z","end":"2026-03-02T18:27:18.562366Z","steps":["trace[1111598042] 'agreement among raft nodes before linearized reading' (duration: 231.737414ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:18.561522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.260105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:27:18.562516Z","caller":"traceutil/trace.go:171","msg":"trace[443155437] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:316; }","duration":"541.277785ms","start":"2026-03-02T18:27:18.021216Z","end":"2026-03-02T18:27:18.562494Z","steps":["trace[443155437] 'agreement among raft nodes before linearized reading' (duration: 540.266545ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:18.562593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:18.021203Z","time spent":"541.374931ms","remote":"127.0.0.1:57276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-03-02T18:27:18.56265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:18.007342Z","time spent":"554.286722ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T18:27:30.224752Z","caller":"traceutil/trace.go:171","msg":"trace[1967024001] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"135.082846ms","start":"2026-03-02T18:27:30.089649Z","end":"2026-03-02T18:27:30.224732Z","steps":["trace[1967024001] 'process raft request' (duration: 103.564211ms)","trace[1967024001] 'compare' (duration: 31.42434ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:27:36.264676Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743063442042098,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-02T18:27:36.716432Z","caller":"traceutil/trace.go:171","msg":"trace[1070560065] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:477; }","duration":"952.779758ms","start":"2026-03-02T18:27:35.763629Z","end":"2026-03-02T18:27:36.716408Z","steps":["trace[1070560065] 'read index received' (duration: 952.589619ms)","trace[1070560065] 'applied index is now lower than readState.Index' (duration: 189.149µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:27:36.716631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.980847ms","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-03-02T18:27:36.716671Z","caller":"traceutil/trace.go:171","msg":"trace[1829907167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:462; }","duration":"953.072902ms","start":"2026-03-02T18:27:35.763586Z","end":"2026-03-02T18:27:36.716659Z","steps":["trace[1829907167] 'agreement among raft nodes before linearized reading' (duration: 952.981447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:36.716705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:35.763568Z","time spent":"953.128695ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T18:27:36.716729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.917917ms","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-03-02T18:27:36.71674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.791035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:27:36.716798Z","caller":"traceutil/trace.go:171","msg":"trace[323803365] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:462; }","duration":"784.020482ms","start":"2026-03-02T18:27:35.932766Z","end":"2026-03-02T18:27:36.716787Z","steps":["trace[323803365] 'agreement among raft nodes before linearized reading' (duration: 783.881115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:36.716828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:35.932752Z","time spent":"784.069304ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-02T18:27:36.716841Z","caller":"traceutil/trace.go:171","msg":"trace[1827432925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"904.952933ms","start":"2026-03-02T18:27:35.811863Z","end":"2026-03-02T18:27:36.716816Z","steps":["trace[1827432925] 'agreement among raft nodes before linearized reading' (duration: 904.765294ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:36.71692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:35.811846Z","time spent":"905.043498ms","remote":"127.0.0.1:57108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-02T18:27:36.716563Z","caller":"traceutil/trace.go:171","msg":"trace[1732140238] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"961.329025ms","start":"2026-03-02T18:27:35.755202Z","end":"2026-03-02T18:27:36.716531Z","steps":["trace[1732140238] 'process raft request' (duration: 961.070673ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:36.717228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:35.755193Z","time spent":"961.810387ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:27:37.58007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.79503ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T18:27:37.580336Z","caller":"traceutil/trace.go:171","msg":"trace[797410070] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"859.512257ms","start":"2026-03-02T18:27:36.720786Z","end":"2026-03-02T18:27:37.580298Z","steps":["trace[797410070] 'process raft request' (duration: 161.004793ms)","trace[797410070] 'compare' (duration: 697.640944ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:27:37.58047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:36.72077Z","time spent":"859.622832ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:27:37.580478Z","caller":"traceutil/trace.go:171","msg":"trace[943409660] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"859.002754ms","start":"2026-03-02T18:27:36.721463Z","end":"2026-03-02T18:27:37.580466Z","steps":["trace[943409660] 'process raft request' (duration: 858.71381ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:27:37.5805Z","caller":"traceutil/trace.go:171","msg":"trace[1873997771] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"858.258678ms","start":"2026-03-02T18:27:36.72223Z","end":"2026-03-02T18:27:37.580489Z","steps":["trace[1873997771] 'process raft request' (duration: 858.007156ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:37.580637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:36.722222Z","time spent":"858.372904ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:27:37.580639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.035513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T18:27:37.580529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:36.721446Z","time spent":"859.058455ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:27:37.58069Z","caller":"traceutil/trace.go:171","msg":"trace[2113926093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:465; }","duration":"767.134068ms","start":"2026-03-02T18:27:36.813544Z","end":"2026-03-02T18:27:37.580678Z","steps":["trace[2113926093] 'agreement among raft nodes before linearized reading' (duration: 767.027233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:37.580725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:36.813525Z","time spent":"767.195671ms","remote":"127.0.0.1:57108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-02T18:27:37.58045Z","caller":"traceutil/trace.go:171","msg":"trace[812132513] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:478; }","duration":"766.843984ms","start":"2026-03-02T18:27:36.813587Z","end":"2026-03-02T18:27:37.580431Z","steps":["trace[812132513] 'read index received' (duration: 68.10687ms)","trace[812132513] 'applied index is now lower than readState.Index' (duration: 698.736124ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:27:37.603986Z","caller":"traceutil/trace.go:171","msg":"trace[1587851622] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"304.010407ms","start":"2026-03-02T18:27:37.299955Z","end":"2026-03-02T18:27:37.603966Z","steps":["trace[1587851622] 'process raft request' (duration: 303.86314ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:37.60422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:37.299924Z","time spent":"304.195046ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:27:40.877261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.36746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-02T18:27:40.877365Z","caller":"traceutil/trace.go:171","msg":"trace[1627794980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:504; }","duration":"144.542957ms","start":"2026-03-02T18:27:40.732805Z","end":"2026-03-02T18:27:40.877348Z","steps":["trace[1627794980] 'agreement among raft nodes before linearized reading' (duration: 144.350469ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:27:40.877004Z","caller":"traceutil/trace.go:171","msg":"trace[391741649] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:520; }","duration":"144.137729ms","start":"2026-03-02T18:27:40.732841Z","end":"2026-03-02T18:27:40.876979Z","steps":["trace[391741649] 'read index received' (duration: 141.555513ms)","trace[391741649] 'applied index is now lower than readState.Index' (duration: 2.580646ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:27:42.112254Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743063442042185,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T18:27:42.613489Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743063442042185,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T18:27:42.62632Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.018234234s","expected-duration":"1s"} {"level":"info","ts":"2026-03-02T18:27:42.626645Z","caller":"traceutil/trace.go:171","msg":"trace[1780343030] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:522; }","duration":"1.014471598s","start":"2026-03-02T18:27:41.612153Z","end":"2026-03-02T18:27:42.626624Z","steps":["trace[1780343030] 'read index received' (duration: 1.014276789s)","trace[1780343030] 'applied index is now lower than readState.Index' (duration: 194.079µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:27:42.626903Z","caller":"traceutil/trace.go:171","msg":"trace[351513468] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"1.018919304s","start":"2026-03-02T18:27:41.607974Z","end":"2026-03-02T18:27:42.626893Z","steps":["trace[351513468] 'process raft request' (duration: 1.018512986s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:42.627002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:41.607948Z","time spent":"1.018979477s","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:27:42.627187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015022672s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-02T18:27:42.627218Z","caller":"traceutil/trace.go:171","msg":"trace[1197036234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:506; }","duration":"1.015084904s","start":"2026-03-02T18:27:41.612123Z","end":"2026-03-02T18:27:42.627208Z","steps":["trace[1197036234] 'agreement among raft nodes before linearized reading' (duration: 1.015017841s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:42.627245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:41.612112Z","time spent":"1.015127466s","remote":"127.0.0.1:57332","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-03-02T18:27:42.627355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015150547s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-02T18:27:42.627474Z","caller":"traceutil/trace.go:171","msg":"trace[1950558943] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:506; }","duration":"1.015304164s","start":"2026-03-02T18:27:41.612147Z","end":"2026-03-02T18:27:42.627452Z","steps":["trace[1950558943] 'agreement among raft nodes before linearized reading' (duration: 1.015114606s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:42.627559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:41.612132Z","time spent":"1.015413239s","remote":"127.0.0.1:57332","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-03-02T18:27:43.023624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.804254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T18:27:43.023743Z","caller":"traceutil/trace.go:171","msg":"trace[90982943] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"391.52211ms","start":"2026-03-02T18:27:42.632198Z","end":"2026-03-02T18:27:43.02372Z","steps":["trace[90982943] 'process raft request' (duration: 230.541649ms)","trace[90982943] 'compare' (duration: 160.563813ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:27:43.023814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:42.63218Z","time spent":"391.603214ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:27:43.286088Z","caller":"traceutil/trace.go:171","msg":"trace[1655122869] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:523; }","duration":"648.170744ms","start":"2026-03-02T18:27:42.637897Z","end":"2026-03-02T18:27:43.286067Z","steps":["trace[1655122869] 'read index received' (duration: 224.956005ms)","trace[1655122869] 'applied index is now lower than readState.Index' (duration: 423.213939ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:27:43.286157Z","caller":"traceutil/trace.go:171","msg":"trace[828554115] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"653.499387ms","start":"2026-03-02T18:27:42.632631Z","end":"2026-03-02T18:27:43.28613Z","steps":["trace[828554115] 'process raft request' (duration: 633.206251ms)","trace[828554115] 'compare' (duration: 20.093187ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:27:43.286287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:42.632613Z","time spent":"653.594231ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:27:43.286432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.373602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-03-02T18:27:43.286465Z","caller":"traceutil/trace.go:171","msg":"trace[19907130] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:508; }","duration":"648.589802ms","start":"2026-03-02T18:27:42.637867Z","end":"2026-03-02T18:27:43.286456Z","steps":["trace[19907130] 'agreement among raft nodes before linearized reading' (duration: 648.365742ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:43.286465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.958304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T18:27:43.286533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:42.637851Z","time spent":"648.673796ms","remote":"127.0.0.1:57248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4397,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-02T18:27:43.286568Z","caller":"traceutil/trace.go:171","msg":"trace[870913411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:508; }","duration":"474.101461ms","start":"2026-03-02T18:27:42.812448Z","end":"2026-03-02T18:27:43.28655Z","steps":["trace[870913411] 'agreement among raft nodes before linearized reading' (duration: 473.920533ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:43.286605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:42.812427Z","time spent":"474.170504ms","remote":"127.0.0.1:57108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T18:27:43.286884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.039362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-02T18:27:43.287036Z","caller":"traceutil/trace.go:171","msg":"trace[1159706600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:508; }","duration":"396.241191ms","start":"2026-03-02T18:27:42.890775Z","end":"2026-03-02T18:27:43.287016Z","steps":["trace[1159706600] 'agreement among raft nodes before linearized reading' (duration: 395.663046ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:43.287175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:42.890755Z","time spent":"396.405228ms","remote":"127.0.0.1:57332","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-03-02T18:27:45.026988Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.736326402s","expected-duration":"1s"} {"level":"info","ts":"2026-03-02T18:27:45.122273Z","caller":"traceutil/trace.go:171","msg":"trace[590352110] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"1.831696242s","start":"2026-03-02T18:27:43.29055Z","end":"2026-03-02T18:27:45.122246Z","steps":["trace[590352110] 'process raft request' (duration: 1.736679188s)","trace[590352110] 'compare' (duration: 94.845927ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:27:45.122426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:43.290523Z","time spent":"1.831826877s","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:27:45.126102Z","caller":"traceutil/trace.go:171","msg":"trace[2066221523] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:525; }","duration":"492.946842ms","start":"2026-03-02T18:27:44.633132Z","end":"2026-03-02T18:27:45.126079Z","steps":["trace[2066221523] 'read index received' (duration: 394.139267ms)","trace[2066221523] 'applied index is now lower than readState.Index' (duration: 98.806335ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:27:45.126144Z","caller":"traceutil/trace.go:171","msg":"trace[1837141261] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.832393368s","start":"2026-03-02T18:27:43.293731Z","end":"2026-03-02T18:27:45.126124Z","steps":["trace[1837141261] 'process raft request' (duration: 1.832175238s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:45.126255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:43.293654Z","time spent":"1.832530084s","remote":"127.0.0.1:57248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:27:45.126307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.165361ms","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-03-02T18:27:45.126346Z","caller":"traceutil/trace.go:171","msg":"trace[501181688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"493.245074ms","start":"2026-03-02T18:27:44.63309Z","end":"2026-03-02T18:27:45.126335Z","steps":["trace[501181688] 'agreement among raft nodes before linearized reading' (duration: 493.110819ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:27:45.126255Z","caller":"traceutil/trace.go:171","msg":"trace[1847657549] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"799.171432ms","start":"2026-03-02T18:27:44.327005Z","end":"2026-03-02T18:27:45.126177Z","steps":["trace[1847657549] 'process raft request' (duration: 799.030836ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:45.126387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:44.63307Z","time spent":"493.309327ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T18:27:45.126645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:44.326983Z","time spent":"799.456203ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:27:45.126674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.887405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:27:45.126843Z","caller":"traceutil/trace.go:171","msg":"trace[1882296972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"314.114964ms","start":"2026-03-02T18:27:44.812623Z","end":"2026-03-02T18:27:45.126738Z","steps":["trace[1882296972] 'agreement among raft nodes before linearized reading' (duration: 313.796491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:45.127107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:44.812608Z","time spent":"314.302283ms","remote":"127.0.0.1:57108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T18:27:51.433875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.809643ms","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-03-02T18:27:51.433968Z","caller":"traceutil/trace.go:171","msg":"trace[1083016674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:549; }","duration":"290.966409ms","start":"2026-03-02T18:27:51.142981Z","end":"2026-03-02T18:27:51.433947Z","steps":["trace[1083016674] 'range keys from in-memory index tree' (duration: 290.631286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:51.434025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.667868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-02T18:27:51.434098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.567045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8gzhs\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-03-02T18:27:51.434025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.885655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-02T18:27:51.43412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.583638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-02T18:27:51.434149Z","caller":"traceutil/trace.go:171","msg":"trace[1332998049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:549; }","duration":"275.823425ms","start":"2026-03-02T18:27:51.158284Z","end":"2026-03-02T18:27:51.434107Z","steps":["trace[1332998049] 'range keys from in-memory index tree' (duration: 275.492291ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:27:51.434156Z","caller":"traceutil/trace.go:171","msg":"trace[859632244] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8gzhs; range_end:; response_count:1; response_revision:549; }","duration":"344.648138ms","start":"2026-03-02T18:27:51.089493Z","end":"2026-03-02T18:27:51.434141Z","steps":["trace[859632244] 'range keys from in-memory index tree' (duration: 344.432149ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:51.434205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:51.089478Z","time spent":"344.71841ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-8gzhs\" "} {"level":"info","ts":"2026-03-02T18:27:51.434218Z","caller":"traceutil/trace.go:171","msg":"trace[1097986491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:549; }","duration":"285.804727ms","start":"2026-03-02T18:27:51.14838Z","end":"2026-03-02T18:27:51.434185Z","steps":["trace[1097986491] 'range keys from in-memory index tree' (duration: 285.312817ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:27:51.434228Z","caller":"traceutil/trace.go:171","msg":"trace[474230721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:549; }","duration":"112.090133ms","start":"2026-03-02T18:27:51.322065Z","end":"2026-03-02T18:27:51.434155Z","steps":["trace[474230721] 'range keys from in-memory index tree' (duration: 111.744829ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:27:51.699587Z","caller":"traceutil/trace.go:171","msg":"trace[735869975] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"260.706741ms","start":"2026-03-02T18:27:51.438519Z","end":"2026-03-02T18:27:51.699226Z","steps":["trace[735869975] 'process raft request' (duration: 260.490582ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:27:52.233502Z","caller":"traceutil/trace.go:171","msg":"trace[801254558] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"792.239906ms","start":"2026-03-02T18:27:51.441243Z","end":"2026-03-02T18:27:52.233483Z","steps":["trace[801254558] 'process raft request' (duration: 792.187054ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:27:52.233545Z","caller":"traceutil/trace.go:171","msg":"trace[1546250340] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"794.458154ms","start":"2026-03-02T18:27:51.439033Z","end":"2026-03-02T18:27:52.233491Z","steps":["trace[1546250340] 'process raft request' (duration: 794.279797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:52.233628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:51.441232Z","time spent":"792.331559ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:27:52.233731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:51.439011Z","time spent":"794.61383ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:27:52.234036Z","caller":"traceutil/trace.go:171","msg":"trace[235011158] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"784.365576ms","start":"2026-03-02T18:27:51.449659Z","end":"2026-03-02T18:27:52.234024Z","steps":["trace[235011158] 'process raft request' (duration: 784.226421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:52.234098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:51.449646Z","time spent":"784.421349ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:27:52.234087Z","caller":"traceutil/trace.go:171","msg":"trace[2112310310] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"791.473426ms","start":"2026-03-02T18:27:51.442561Z","end":"2026-03-02T18:27:52.234034Z","steps":["trace[2112310310] 'process raft request' (duration: 790.898813ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:52.234199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:51.442551Z","time spent":"791.586421ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:27:53.341264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.697908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:27:53.341315Z","caller":"traceutil/trace.go:171","msg":"trace[1803324461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"529.795292ms","start":"2026-03-02T18:27:52.811507Z","end":"2026-03-02T18:27:53.341302Z","steps":["trace[1803324461] 'range keys from in-memory index tree' (duration: 529.566523ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:53.341339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:52.811487Z","time spent":"529.846894ms","remote":"127.0.0.1:57108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T18:27:54.312377Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743063442042288,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T18:27:54.813426Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743063442042288,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T18:27:55.313606Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743063442042288,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T18:27:55.811968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000393878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-02T18:27:55.812048Z","caller":"traceutil/trace.go:171","msg":"trace[2072620080] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000523642s","start":"2026-03-02T18:27:53.811507Z","end":"2026-03-02T18:27:55.812031Z","steps":["trace[2072620080] 'agreement among raft nodes before linearized reading' (duration: 2.000425199s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:55.812142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:53.811486Z","time spent":"2.000645557s","remote":"127.0.0.1:57108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/02 18:27:55 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-02T18:27:55.814263Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743063442042288,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T18:27:55.96611Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.258121067s","expected-duration":"1s"} {"level":"info","ts":"2026-03-02T18:27:55.966493Z","caller":"traceutil/trace.go:171","msg":"trace[1185875410] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"2.258624196s","start":"2026-03-02T18:27:53.707846Z","end":"2026-03-02T18:27:55.96647Z","steps":["trace[1185875410] 'process raft request' (duration: 2.258442259s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:55.966642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:53.707811Z","time spent":"2.258756861s","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:27:57.812916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000390436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-02T18:27:57.813018Z","caller":"traceutil/trace.go:171","msg":"trace[1891040384] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000551041s","start":"2026-03-02T18:27:55.812445Z","end":"2026-03-02T18:27:57.812996Z","steps":["trace[1891040384] 'agreement among raft nodes before linearized reading' (duration: 2.000425647s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:57.813079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:55.812423Z","time spent":"2.000642985s","remote":"127.0.0.1:57096","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-03-02T18:27:59.092441Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.126106102s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-02T18:27:59.238391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.000087007s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-02T18:27:59.238502Z","caller":"traceutil/trace.go:171","msg":"trace[1311639630] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"5.000236763s","start":"2026-03-02T18:27:54.238234Z","end":"2026-03-02T18:27:59.23847Z","steps":["trace[1311639630] 'agreement among raft nodes before linearized reading' (duration: 5.000118829s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:59.238391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.000015875s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"warn","ts":"2026-03-02T18:27:59.238562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:54.238195Z","time spent":"5.000352577s","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-02T18:27:59.238591Z","caller":"traceutil/trace.go:171","msg":"trace[1920310387] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"5.000232733s","start":"2026-03-02T18:27:54.238341Z","end":"2026-03-02T18:27:59.238574Z","steps":["trace[1920310387] 'agreement among raft nodes before linearized reading' (duration: 5.000030246s)"],"step_count":1} 2026/03/02 18:27:59 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-02T18:27:59.23864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:54.238336Z","time spent":"5.000290365s","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/03/02 18:27:59 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-02T18:27:59.743941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.188113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T18:27:59.744044Z","caller":"traceutil/trace.go:171","msg":"trace[1573332198] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:573; }","duration":"5.932466698s","start":"2026-03-02T18:27:53.811561Z","end":"2026-03-02T18:27:59.744027Z","steps":["trace[1573332198] 'read index received' (duration: 2.15478584s)","trace[1573332198] 'applied index is now lower than readState.Index' (duration: 3.777679868s)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:27:59.744117Z","caller":"traceutil/trace.go:171","msg":"trace[283349810] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"4.595675328s","start":"2026-03-02T18:27:55.148431Z","end":"2026-03-02T18:27:59.744107Z","steps":["trace[283349810] 'process raft request' (duration: 3.944237687s)","trace[283349810] 'compare' (duration: 651.091399ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:27:59.744209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:55.148405Z","time spent":"4.595742731s","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:27:59.820479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000479756s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-02T18:27:59.820559Z","caller":"traceutil/trace.go:171","msg":"trace[2127273953] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00059157s","start":"2026-03-02T18:27:57.819937Z","end":"2026-03-02T18:27:59.820528Z","steps":["trace[2127273953] 'agreement among raft nodes before linearized reading' (duration: 2.000503737s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:27:59.820613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:57.819921Z","time spent":"2.000684414s","remote":"127.0.0.1:57108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/02 18:27:59 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-02T18:28:00.021995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.193273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-02T18:28:00.022235Z","caller":"traceutil/trace.go:171","msg":"trace[1787628109] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:575; }","duration":"278.137458ms","start":"2026-03-02T18:27:59.744077Z","end":"2026-03-02T18:28:00.022214Z","steps":["trace[1787628109] 'read index received' (duration: 135.599813ms)","trace[1787628109] 'applied index is now lower than readState.Index' (duration: 142.536655ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:28:00.022272Z","caller":"traceutil/trace.go:171","msg":"trace[1243182619] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"2.021763035s","start":"2026-03-02T18:27:58.000483Z","end":"2026-03-02T18:28:00.022246Z","steps":["trace[1243182619] 'process raft request' (duration: 2.02162282s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:28:00.022435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.051365891s","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-03-02T18:28:00.022435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.784252403s","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-03-02T18:28:00.02248Z","caller":"traceutil/trace.go:171","msg":"trace[453316153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"2.051450974s","start":"2026-03-02T18:27:57.971018Z","end":"2026-03-02T18:28:00.022469Z","steps":["trace[453316153] 'agreement among raft nodes before linearized reading' (duration: 2.051307519s)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:28:00.022493Z","caller":"traceutil/trace.go:171","msg":"trace[320288751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"5.784355817s","start":"2026-03-02T18:27:54.238126Z","end":"2026-03-02T18:28:00.022482Z","steps":["trace[320288751] 'agreement among raft nodes before linearized reading' (duration: 5.784208881s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:28:00.022518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:57.970999Z","time spent":"2.051510597s","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T18:28:00.022528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:54.238102Z","time spent":"5.784417379s","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-02T18:28:00.022521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.847542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T18:28:00.022442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:58.000466Z","time spent":"2.02188514s","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:28:00.022578Z","caller":"traceutil/trace.go:171","msg":"trace[148716031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"201.929545ms","start":"2026-03-02T18:27:59.820634Z","end":"2026-03-02T18:28:00.022564Z","steps":["trace[148716031] 'agreement among raft nodes before linearized reading' (duration: 201.859392ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:28:00.022566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.706659334s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-02T18:28:00.022263Z","caller":"traceutil/trace.go:171","msg":"trace[959656377] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"2.206131447s","start":"2026-03-02T18:27:57.816084Z","end":"2026-03-02T18:28:00.022216Z","steps":["trace[959656377] 'process raft request' (duration: 2.063645754s)","trace[959656377] 'compare' (duration: 142.014887ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:28:00.022669Z","caller":"traceutil/trace.go:171","msg":"trace[691217642] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:558; }","duration":"4.706805519s","start":"2026-03-02T18:27:55.315835Z","end":"2026-03-02T18:28:00.022641Z","steps":["trace[691217642] 'agreement among raft nodes before linearized reading' (duration: 4.706507999s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:28:00.022712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:55.315816Z","time spent":"4.706887612s","remote":"127.0.0.1:57120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"warn","ts":"2026-03-02T18:28:00.022719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:27:57.816056Z","time spent":"2.206634375s","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T18:28:00.249158Z","caller":"traceutil/trace.go:171","msg":"trace[331649451] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"221.101262ms","start":"2026-03-02T18:28:00.028031Z","end":"2026-03-02T18:28:00.249132Z","steps":["trace[331649451] 'read index received' (duration: 148.893221ms)","trace[331649451] 'applied index is now lower than readState.Index' (duration: 72.206641ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:28:00.249286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.225256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.1899199a0efa6de1\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-03-02T18:28:00.249348Z","caller":"traceutil/trace.go:171","msg":"trace[970108452] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.1899199a0efa6de1; range_end:; response_count:1; response_revision:558; }","duration":"221.33445ms","start":"2026-03-02T18:28:00.027996Z","end":"2026-03-02T18:28:00.24933Z","steps":["trace[970108452] 'agreement among raft nodes before linearized reading' (duration: 221.214196ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:28:00.249707Z","caller":"traceutil/trace.go:171","msg":"trace[24588480] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"221.414742ms","start":"2026-03-02T18:28:00.028273Z","end":"2026-03-02T18:28:00.249688Z","steps":["trace[24588480] 'process raft request' (duration: 221.242216ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:28:00.24975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.098735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:28:00.249809Z","caller":"traceutil/trace.go:171","msg":"trace[1634022830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"218.185028ms","start":"2026-03-02T18:28:00.031612Z","end":"2026-03-02T18:28:00.249797Z","steps":["trace[1634022830] 'agreement among raft nodes before linearized reading' (duration: 218.059904ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:28:00.249608Z","caller":"traceutil/trace.go:171","msg":"trace[467902932] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"221.440223ms","start":"2026-03-02T18:28:00.028153Z","end":"2026-03-02T18:28:00.249594Z","steps":["trace[467902932] 'process raft request' (duration: 220.876553ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:28:00.493088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.993785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T18:28:00.493304Z","caller":"traceutil/trace.go:171","msg":"trace[58159832] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"243.33745ms","start":"2026-03-02T18:28:00.249934Z","end":"2026-03-02T18:28:00.493272Z","steps":["trace[58159832] 'process raft request' (duration: 123.052324ms)","trace[58159832] 'compare' (duration: 119.887192ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:28:00.493337Z","caller":"traceutil/trace.go:171","msg":"trace[334448682] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"241.426352ms","start":"2026-03-02T18:28:00.251875Z","end":"2026-03-02T18:28:00.493301Z","steps":["trace[334448682] 'process raft request' (duration: 241.319148ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:28:02.23837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.946048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:28:02.238426Z","caller":"traceutil/trace.go:171","msg":"trace[960769377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"550.047891ms","start":"2026-03-02T18:28:01.688363Z","end":"2026-03-02T18:28:02.238411Z","steps":["trace[960769377] 'range keys from in-memory index tree' (duration: 549.837064ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:28:02.238386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.649284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T18:28:02.238453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:28:01.688335Z","time spent":"550.112623ms","remote":"127.0.0.1:57108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-02T18:28:02.238458Z","caller":"traceutil/trace.go:171","msg":"trace[2127279864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"426.765237ms","start":"2026-03-02T18:28:01.811683Z","end":"2026-03-02T18:28:02.238449Z","steps":["trace[2127279864] 'range keys from in-memory index tree' (duration: 426.525999ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:28:02.238479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:28:01.811664Z","time spent":"426.809799ms","remote":"127.0.0.1:57096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T18:28:05.000359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.36394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T18:28:05.000404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.853522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-03-02T18:28:05.000435Z","caller":"traceutil/trace.go:171","msg":"trace[259298862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"185.484425ms","start":"2026-03-02T18:28:04.814935Z","end":"2026-03-02T18:28:05.00042Z","steps":["trace[259298862] 'range keys from in-memory index tree' (duration: 185.284098ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:28:05.000454Z","caller":"traceutil/trace.go:171","msg":"trace[141075295] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:627; }","duration":"326.962205ms","start":"2026-03-02T18:28:04.673476Z","end":"2026-03-02T18:28:05.000438Z","steps":["trace[141075295] 'range keys from in-memory index tree' (duration: 326.652765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:28:05.0005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:28:04.673448Z","time spent":"327.034578ms","remote":"127.0.0.1:57142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4202,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-03-02T18:28:05.000361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.948046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-03-02T18:28:05.000591Z","caller":"traceutil/trace.go:171","msg":"trace[82306503] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:627; }","duration":"124.222426ms","start":"2026-03-02T18:28:04.87635Z","end":"2026-03-02T18:28:05.000572Z","steps":["trace[82306503] 'range keys from in-memory index tree' (duration: 123.799641ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:28:05.931614Z","caller":"traceutil/trace.go:171","msg":"trace[746748034] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"102.202851ms","start":"2026-03-02T18:28:05.82938Z","end":"2026-03-02T18:28:05.931583Z","steps":["trace[746748034] 'process raft request' (duration: 101.755186ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:28:06.326364Z","caller":"traceutil/trace.go:171","msg":"trace[1692537267] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"112.658801ms","start":"2026-03-02T18:28:06.213669Z","end":"2026-03-02T18:28:06.326328Z","steps":["trace[1692537267] 'process raft request' (duration: 112.137474ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:28:06.506063Z","caller":"traceutil/trace.go:171","msg":"trace[1949905434] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"114.572895ms","start":"2026-03-02T18:28:06.391446Z","end":"2026-03-02T18:28:06.506019Z","steps":["trace[1949905434] 'process raft request' (duration: 63.767568ms)","trace[1949905434] 'compare' (duration: 50.631271ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:28:06.506256Z","caller":"traceutil/trace.go:171","msg":"trace[780693784] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"114.658837ms","start":"2026-03-02T18:28:06.391583Z","end":"2026-03-02T18:28:06.506242Z","steps":["trace[780693784] 'process raft request' (duration: 114.381208ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:28:06.506397Z","caller":"traceutil/trace.go:171","msg":"trace[1708977116] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"114.755791ms","start":"2026-03-02T18:28:06.39162Z","end":"2026-03-02T18:28:06.506376Z","steps":["trace[1708977116] 'process raft request' (duration: 114.568834ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:28:06.549229Z","caller":"traceutil/trace.go:171","msg":"trace[425615346] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"152.015667ms","start":"2026-03-02T18:28:06.397182Z","end":"2026-03-02T18:28:06.549198Z","steps":["trace[425615346] 'process raft request' (duration: 151.857702ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:28:06.549362Z","caller":"traceutil/trace.go:171","msg":"trace[420798706] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:670; }","duration":"152.345169ms","start":"2026-03-02T18:28:06.396995Z","end":"2026-03-02T18:28:06.54934Z","steps":["trace[420798706] 'read index received' (duration: 58.245615ms)","trace[420798706] 'applied index is now lower than readState.Index' (duration: 94.098694ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:28:06.549455Z","caller":"traceutil/trace.go:171","msg":"trace[1469385268] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"144.598181ms","start":"2026-03-02T18:28:06.404801Z","end":"2026-03-02T18:28:06.549399Z","steps":["trace[1469385268] 'process raft request' (duration: 144.375664ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:28:06.549554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.538725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-9p52q\" ","response":"range_response_count:1 size:3835"} {"level":"info","ts":"2026-03-02T18:28:06.549608Z","caller":"traceutil/trace.go:171","msg":"trace[531521341] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-9p52q; range_end:; response_count:1; response_revision:653; }","duration":"152.627609ms","start":"2026-03-02T18:28:06.396965Z","end":"2026-03-02T18:28:06.549593Z","steps":["trace[531521341] 'agreement among raft nodes before linearized reading' (duration: 152.460803ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:28:20.839337Z","caller":"traceutil/trace.go:171","msg":"trace[1437382420] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"130.749352ms","start":"2026-03-02T18:28:20.708572Z","end":"2026-03-02T18:28:20.839321Z","steps":["trace[1437382420] 'read index received' (duration: 130.664469ms)","trace[1437382420] 'applied index is now lower than readState.Index' (duration: 84.173µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:28:20.839438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.850584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:28:20.839458Z","caller":"traceutil/trace.go:171","msg":"trace[379741891] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:767; }","duration":"130.930396ms","start":"2026-03-02T18:28:20.708522Z","end":"2026-03-02T18:28:20.839452Z","steps":["trace[379741891] 'agreement among raft nodes before linearized reading' (duration: 130.878975ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:28:20.839436Z","caller":"traceutil/trace.go:171","msg":"trace[939246735] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"151.345122ms","start":"2026-03-02T18:28:20.688039Z","end":"2026-03-02T18:28:20.839384Z","steps":["trace[939246735] 'process raft request' (duration: 151.194618ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:28:20.84329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.645363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:28:20.843349Z","caller":"traceutil/trace.go:171","msg":"trace[1370984983] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:767; }","duration":"134.729715ms","start":"2026-03-02T18:28:20.708604Z","end":"2026-03-02T18:28:20.843334Z","steps":["trace[1370984983] 'agreement among raft nodes before linearized reading' (duration: 134.613482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:28:20.843362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.82038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:28:20.843398Z","caller":"traceutil/trace.go:171","msg":"trace[30351977] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:767; }","duration":"133.879261ms","start":"2026-03-02T18:28:20.709509Z","end":"2026-03-02T18:28:20.843388Z","steps":["trace[30351977] 'agreement among raft nodes before linearized reading' (duration: 133.811789ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:09.712494Z","caller":"traceutil/trace.go:171","msg":"trace[1835210866] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"104.566842ms","start":"2026-03-02T18:29:09.607898Z","end":"2026-03-02T18:29:09.712465Z","steps":["trace[1835210866] 'process raft request' (duration: 98.10664ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:17.31431Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743063442043244,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-02T18:29:17.465429Z","caller":"traceutil/trace.go:171","msg":"trace[1695982207] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"714.403576ms","start":"2026-03-02T18:29:16.750998Z","end":"2026-03-02T18:29:17.465401Z","steps":["trace[1695982207] 'process raft request' (duration: 714.255793ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:17.465647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:16.750983Z","time spent":"714.577628ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:29:17.468857Z","caller":"traceutil/trace.go:171","msg":"trace[1068959841] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"655.638719ms","start":"2026-03-02T18:29:16.813194Z","end":"2026-03-02T18:29:17.468833Z","steps":["trace[1068959841] 'read index received' (duration: 652.241638ms)","trace[1068959841] 'applied index is now lower than readState.Index' (duration: 3.396001ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:29:17.468951Z","caller":"traceutil/trace.go:171","msg":"trace[1071071432] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"717.068333ms","start":"2026-03-02T18:29:16.75187Z","end":"2026-03-02T18:29:17.468938Z","steps":["trace[1071071432] 'process raft request' (duration: 716.850379ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:17.469006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.804982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:29:17.469056Z","caller":"traceutil/trace.go:171","msg":"trace[599414486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"655.893534ms","start":"2026-03-02T18:29:16.813146Z","end":"2026-03-02T18:29:17.46904Z","steps":["trace[599414486] 'agreement among raft nodes before linearized reading' (duration: 655.815812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:17.4691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:16.813127Z","time spent":"655.960854ms","remote":"127.0.0.1:57108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T18:29:17.469124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.318108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T18:29:17.469165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.277047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-02T18:29:17.469145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.585597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:29:17.469218Z","caller":"traceutil/trace.go:171","msg":"trace[1615229639] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:958; }","duration":"372.364079ms","start":"2026-03-02T18:29:17.096842Z","end":"2026-03-02T18:29:17.469206Z","steps":["trace[1615229639] 'agreement among raft nodes before linearized reading' (duration: 372.207416ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:17.469219Z","caller":"traceutil/trace.go:171","msg":"trace[336774980] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:958; }","duration":"618.45854ms","start":"2026-03-02T18:29:16.850739Z","end":"2026-03-02T18:29:17.469197Z","steps":["trace[336774980] 'agreement among raft nodes before linearized reading' (duration: 618.287227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:17.469243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:17.09682Z","time spent":"372.414519ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-02T18:29:17.469236Z","caller":"traceutil/trace.go:171","msg":"trace[1860819399] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:958; }","duration":"272.74474ms","start":"2026-03-02T18:29:17.196473Z","end":"2026-03-02T18:29:17.469218Z","steps":["trace[1860819399] 'agreement among raft nodes before linearized reading' (duration: 272.558686ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:17.469277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:16.850724Z","time spent":"618.537022ms","remote":"127.0.0.1:57598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-02T18:29:17.469024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:16.75184Z","time spent":"717.139945ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:29:25.775773Z","caller":"traceutil/trace.go:171","msg":"trace[1378764003] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"273.121176ms","start":"2026-03-02T18:29:25.502631Z","end":"2026-03-02T18:29:25.775752Z","steps":["trace[1378764003] 'process raft request' (duration: 236.333201ms)","trace[1378764003] 'compare' (duration: 36.48816ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:29:25.775947Z","caller":"traceutil/trace.go:171","msg":"trace[908905207] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1028; }","duration":"267.883667ms","start":"2026-03-02T18:29:25.50804Z","end":"2026-03-02T18:29:25.775924Z","steps":["trace[908905207] 'read index received' (duration: 230.920009ms)","trace[908905207] 'applied index is now lower than readState.Index' (duration: 36.962058ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:29:25.776061Z","caller":"traceutil/trace.go:171","msg":"trace[262384272] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"273.046284ms","start":"2026-03-02T18:29:25.503005Z","end":"2026-03-02T18:29:25.776051Z","steps":["trace[262384272] 'process raft request' (duration: 272.684418ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:25.776491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.385945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-02T18:29:25.776644Z","caller":"traceutil/trace.go:171","msg":"trace[1389886195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:988; }","duration":"268.603058ms","start":"2026-03-02T18:29:25.508015Z","end":"2026-03-02T18:29:25.776618Z","steps":["trace[1389886195] 'agreement among raft nodes before linearized reading' (duration: 268.11954ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:26.008433Z","caller":"traceutil/trace.go:171","msg":"trace[205618074] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"215.63264ms","start":"2026-03-02T18:29:25.792767Z","end":"2026-03-02T18:29:26.0084Z","steps":["trace[205618074] 'process raft request' (duration: 163.19786ms)","trace[205618074] 'compare' (duration: 52.304678ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:29:26.335454Z","caller":"traceutil/trace.go:171","msg":"trace[430449803] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"542.585766ms","start":"2026-03-02T18:29:25.792848Z","end":"2026-03-02T18:29:26.335434Z","steps":["trace[430449803] 'process raft request' (duration: 542.423624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:26.335635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:25.792825Z","time spent":"542.742869ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:29:26.335629Z","caller":"traceutil/trace.go:171","msg":"trace[283004100] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"541.06848ms","start":"2026-03-02T18:29:25.794533Z","end":"2026-03-02T18:29:26.335601Z","steps":["trace[283004100] 'process raft request' (duration: 540.855336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:26.335796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:25.794522Z","time spent":"541.252283ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:29:38.061826Z","caller":"traceutil/trace.go:171","msg":"trace[1926074276] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1068; }","duration":"163.76135ms","start":"2026-03-02T18:29:37.898037Z","end":"2026-03-02T18:29:38.061798Z","steps":["trace[1926074276] 'read index received' (duration: 30.32587ms)","trace[1926074276] 'applied index is now lower than readState.Index' (duration: 133.43392ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:29:38.061843Z","caller":"traceutil/trace.go:171","msg":"trace[1801597980] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"197.463204ms","start":"2026-03-02T18:29:37.864349Z","end":"2026-03-02T18:29:38.061813Z","steps":["trace[1801597980] 'process raft request' (duration: 196.712732ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:38.061998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.940892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:persistent-volume-binder\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-03-02T18:29:38.062073Z","caller":"traceutil/trace.go:171","msg":"trace[508063528] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:persistent-volume-binder; range_end:; response_count:1; response_revision:1025; }","duration":"164.051914ms","start":"2026-03-02T18:29:37.898002Z","end":"2026-03-02T18:29:38.062053Z","steps":["trace[508063528] 'agreement among raft nodes before linearized reading' (duration: 163.924432ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:38.062095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.479839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:node-controller\" ","response":"range_response_count:1 size:735"} {"level":"info","ts":"2026-03-02T18:29:38.062129Z","caller":"traceutil/trace.go:171","msg":"trace[1791380217] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:node-controller; range_end:; response_count:1; response_revision:1025; }","duration":"162.541721ms","start":"2026-03-02T18:29:37.899575Z","end":"2026-03-02T18:29:38.062117Z","steps":["trace[1791380217] 'agreement among raft nodes before linearized reading' (duration: 162.369958ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:42.033124Z","caller":"traceutil/trace.go:171","msg":"trace[1402924721] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"157.119835ms","start":"2026-03-02T18:29:41.875965Z","end":"2026-03-02T18:29:42.033084Z","steps":["trace[1402924721] 'process raft request' (duration: 156.944492ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:42.033223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.744198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-03-02T18:29:42.03327Z","caller":"traceutil/trace.go:171","msg":"trace[1945499880] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/kube-root-ca.crt; range_end:; response_count:1; response_revision:1034; }","duration":"156.812359ms","start":"2026-03-02T18:29:41.876447Z","end":"2026-03-02T18:29:42.033259Z","steps":["trace[1945499880] 'agreement among raft nodes before linearized reading' (duration: 156.717068ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:42.033102Z","caller":"traceutil/trace.go:171","msg":"trace[1775442848] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"156.591336ms","start":"2026-03-02T18:29:41.876458Z","end":"2026-03-02T18:29:42.03305Z","steps":["trace[1775442848] 'read index received' (duration: 156.376233ms)","trace[1775442848] 'applied index is now lower than readState.Index' (duration: 214.073µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:29:42.033963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.108655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/default/\" range_end:\"/registry/deployments/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:29:42.034009Z","caller":"traceutil/trace.go:171","msg":"trace[1019156108] range","detail":"{range_begin:/registry/deployments/default/; range_end:/registry/deployments/default0; response_count:0; response_revision:1034; }","duration":"103.500712ms","start":"2026-03-02T18:29:41.930494Z","end":"2026-03-02T18:29:42.033994Z","steps":["trace[1019156108] 'agreement among raft nodes before linearized reading' (duration: 103.122006ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:42.287201Z","caller":"traceutil/trace.go:171","msg":"trace[243718542] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"249.573521ms","start":"2026-03-02T18:29:42.037605Z","end":"2026-03-02T18:29:42.287178Z","steps":["trace[243718542] 'process raft request' (duration: 181.292128ms)","trace[243718542] 'compare' (duration: 67.975469ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:29:42.287599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.388463ms","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-03-02T18:29:42.287686Z","caller":"traceutil/trace.go:171","msg":"trace[929847642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1035; }","duration":"204.520216ms","start":"2026-03-02T18:29:42.083154Z","end":"2026-03-02T18:29:42.287674Z","steps":["trace[929847642] 'agreement among raft nodes before linearized reading' (duration: 204.083049ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:42.2872Z","caller":"traceutil/trace.go:171","msg":"trace[2017637187] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"203.952067ms","start":"2026-03-02T18:29:42.083199Z","end":"2026-03-02T18:29:42.287151Z","steps":["trace[2017637187] 'read index received' (duration: 135.715184ms)","trace[2017637187] 'applied index is now lower than readState.Index' (duration: 68.235593ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:29:42.287956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.538116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-node-lease/\" range_end:\"/registry/services/endpoints/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:29:42.288023Z","caller":"traceutil/trace.go:171","msg":"trace[237977925] range","detail":"{range_begin:/registry/services/endpoints/kube-node-lease/; range_end:/registry/services/endpoints/kube-node-lease0; response_count:0; response_revision:1035; }","duration":"180.608707ms","start":"2026-03-02T18:29:42.107402Z","end":"2026-03-02T18:29:42.28801Z","steps":["trace[237977925] 'agreement among raft nodes before linearized reading' (duration: 180.518416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:42.288057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.138994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-02T18:29:42.288136Z","caller":"traceutil/trace.go:171","msg":"trace[2040875090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1035; }","duration":"120.285326ms","start":"2026-03-02T18:29:42.167836Z","end":"2026-03-02T18:29:42.288122Z","steps":["trace[2040875090] 'agreement among raft nodes before linearized reading' (duration: 120.090673ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:42.288136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.559709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/\" range_end:\"/registry/services/endpoints/default0\" limit:500 ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-02T18:29:42.288206Z","caller":"traceutil/trace.go:171","msg":"trace[741163948] range","detail":"{range_begin:/registry/services/endpoints/default/; range_end:/registry/services/endpoints/default0; response_count:1; response_revision:1035; }","duration":"175.675961ms","start":"2026-03-02T18:29:42.112514Z","end":"2026-03-02T18:29:42.28819Z","steps":["trace[741163948] 'agreement among raft nodes before linearized reading' (duration: 175.557519ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:43.177544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.168871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T18:29:43.177697Z","caller":"traceutil/trace.go:171","msg":"trace[319125788] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"884.783397ms","start":"2026-03-02T18:29:42.292887Z","end":"2026-03-02T18:29:43.177671Z","steps":["trace[319125788] 'process raft request' (duration: 469.396942ms)","trace[319125788] 'compare' (duration: 414.755085ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:29:43.177734Z","caller":"traceutil/trace.go:171","msg":"trace[479436361] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"527.644136ms","start":"2026-03-02T18:29:42.650072Z","end":"2026-03-02T18:29:43.177716Z","steps":["trace[479436361] 'process raft request' (duration: 527.563535ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:43.177766Z","caller":"traceutil/trace.go:171","msg":"trace[1288499710] linearizableReadLoop","detail":"{readStateIndex:1083; appliedIndex:1082; }","duration":"816.636495ms","start":"2026-03-02T18:29:42.361109Z","end":"2026-03-02T18:29:43.177745Z","steps":["trace[1288499710] 'read index received' (duration: 401.192039ms)","trace[1288499710] 'applied index is now lower than readState.Index' (duration: 415.442616ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:29:43.177801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:42.650059Z","time spent":"527.710347ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:29:43.177826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:42.292879Z","time spent":"884.874548ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:29:43.177927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.803247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/default/\" range_end:\"/registry/ingress/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:29:43.177974Z","caller":"traceutil/trace.go:171","msg":"trace[104578925] range","detail":"{range_begin:/registry/ingress/default/; range_end:/registry/ingress/default0; response_count:0; response_revision:1038; }","duration":"816.883969ms","start":"2026-03-02T18:29:42.361076Z","end":"2026-03-02T18:29:43.17796Z","steps":["trace[104578925] 'agreement among raft nodes before linearized reading' (duration: 816.780077ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:43.178017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:42.36106Z","time spent":"816.94469ms","remote":"127.0.0.1:57360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/default/\" range_end:\"/registry/ingress/default0\" limit:500 "} {"level":"warn","ts":"2026-03-02T18:29:43.17825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.890861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/kube-node-lease/\" range_end:\"/registry/ingress/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:29:43.178294Z","caller":"traceutil/trace.go:171","msg":"trace[1972129940] range","detail":"{range_begin:/registry/ingress/kube-node-lease/; range_end:/registry/ingress/kube-node-lease0; response_count:0; response_revision:1038; }","duration":"811.952643ms","start":"2026-03-02T18:29:42.366328Z","end":"2026-03-02T18:29:43.178281Z","steps":["trace[1972129940] 'agreement among raft nodes before linearized reading' (duration: 811.886012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:43.178294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.656558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T18:29:43.178337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:42.366322Z","time spent":"812.000994ms","remote":"127.0.0.1:57360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/kube-node-lease/\" range_end:\"/registry/ingress/kube-node-lease0\" limit:500 "} {"level":"warn","ts":"2026-03-02T18:29:43.178353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.15726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-02T18:29:43.178366Z","caller":"traceutil/trace.go:171","msg":"trace[912631061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1038; }","duration":"365.756539ms","start":"2026-03-02T18:29:42.812585Z","end":"2026-03-02T18:29:43.178342Z","steps":["trace[912631061] 'agreement among raft nodes before linearized reading' (duration: 365.639997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:43.178397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:42.81257Z","time spent":"365.82142ms","remote":"127.0.0.1:57108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-02T18:29:43.178393Z","caller":"traceutil/trace.go:171","msg":"trace[1134547484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1038; }","duration":"728.217691ms","start":"2026-03-02T18:29:42.450162Z","end":"2026-03-02T18:29:43.17838Z","steps":["trace[1134547484] 'agreement among raft nodes before linearized reading' (duration: 728.092359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:43.178402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.198847ms","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":"warn","ts":"2026-03-02T18:29:43.178425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:42.450148Z","time spent":"728.269731ms","remote":"127.0.0.1:57332","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-03-02T18:29:43.178441Z","caller":"traceutil/trace.go:171","msg":"trace[10539202] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1038; }","duration":"683.258307ms","start":"2026-03-02T18:29:42.495171Z","end":"2026-03-02T18:29:43.17843Z","steps":["trace[10539202] 'agreement among raft nodes before linearized reading' (duration: 683.133255ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:43.178458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.110874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-03-02T18:29:43.178471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:42.495161Z","time spent":"683.303888ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-02T18:29:43.178497Z","caller":"traceutil/trace.go:171","msg":"trace[1314676607] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1038; }","duration":"762.185725ms","start":"2026-03-02T18:29:42.416298Z","end":"2026-03-02T18:29:43.178484Z","steps":["trace[1314676607] 'agreement among raft nodes before linearized reading' (duration: 762.109824ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:43.17852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:42.416275Z","time spent":"762.239846ms","remote":"127.0.0.1:57246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-03-02T18:29:43.414381Z","caller":"traceutil/trace.go:171","msg":"trace[202287961] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1084; }","duration":"161.264356ms","start":"2026-03-02T18:29:43.253088Z","end":"2026-03-02T18:29:43.414353Z","steps":["trace[202287961] 'read index received' (duration: 10.298128ms)","trace[202287961] 'applied index is now lower than readState.Index' (duration: 150.964608ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:29:43.414446Z","caller":"traceutil/trace.go:171","msg":"trace[864070945] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"230.142313ms","start":"2026-03-02T18:29:43.184265Z","end":"2026-03-02T18:29:43.414407Z","steps":["trace[864070945] 'process raft request' (duration: 209.830761ms)","trace[864070945] 'compare' (duration: 20.125349ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:29:43.414599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.487909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-03-02T18:29:43.414598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.286121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/default/\" range_end:\"/registry/jobs/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:29:43.414662Z","caller":"traceutil/trace.go:171","msg":"trace[2025555905] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1040; }","duration":"161.600321ms","start":"2026-03-02T18:29:43.253041Z","end":"2026-03-02T18:29:43.414641Z","steps":["trace[2025555905] 'agreement among raft nodes before linearized reading' (duration: 161.465619ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:43.414676Z","caller":"traceutil/trace.go:171","msg":"trace[515610142] range","detail":"{range_begin:/registry/jobs/default/; range_end:/registry/jobs/default0; response_count:0; response_revision:1040; }","duration":"160.378382ms","start":"2026-03-02T18:29:43.254282Z","end":"2026-03-02T18:29:43.41466Z","steps":["trace[515610142] 'agreement among raft nodes before linearized reading' (duration: 160.25552ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:43.414679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.845357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/kube-node-lease/\" range_end:\"/registry/jobs/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:29:43.414737Z","caller":"traceutil/trace.go:171","msg":"trace[391516249] range","detail":"{range_begin:/registry/jobs/kube-node-lease/; range_end:/registry/jobs/kube-node-lease0; response_count:0; response_revision:1040; }","duration":"152.948638ms","start":"2026-03-02T18:29:43.261773Z","end":"2026-03-02T18:29:43.414722Z","steps":["trace[391516249] 'agreement among raft nodes before linearized reading' (duration: 152.821966ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:43.6689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.360523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/\" range_end:\"/registry/pods/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:29:43.668963Z","caller":"traceutil/trace.go:171","msg":"trace[1285977783] range","detail":"{range_begin:/registry/pods/default/; range_end:/registry/pods/default0; response_count:0; response_revision:1040; }","duration":"108.454414ms","start":"2026-03-02T18:29:43.560496Z","end":"2026-03-02T18:29:43.66895Z","steps":["trace[1285977783] 'range keys from in-memory index tree' (duration: 108.304002ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:44.530553Z","caller":"traceutil/trace.go:171","msg":"trace[161708265] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1086; }","duration":"230.45395ms","start":"2026-03-02T18:29:44.300045Z","end":"2026-03-02T18:29:44.530498Z","steps":["trace[161708265] 'read index received' (duration: 230.234177ms)","trace[161708265] 'applied index is now lower than readState.Index' (duration: 218.553µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:29:44.53063Z","caller":"traceutil/trace.go:171","msg":"trace[2064738830] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"237.181492ms","start":"2026-03-02T18:29:44.293427Z","end":"2026-03-02T18:29:44.530608Z","steps":["trace[2064738830] 'process raft request' (duration: 236.935608ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:44.530733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.196639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-node-lease/\" range_end:\"/registry/rolebindings/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:29:44.530792Z","caller":"traceutil/trace.go:171","msg":"trace[495293999] range","detail":"{range_begin:/registry/rolebindings/kube-node-lease/; range_end:/registry/rolebindings/kube-node-lease0; response_count:0; response_revision:1041; }","duration":"208.272241ms","start":"2026-03-02T18:29:44.322504Z","end":"2026-03-02T18:29:44.530776Z","steps":["trace[495293999] 'agreement among raft nodes before linearized reading' (duration: 208.168179ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:44.530785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.104472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/default/\" range_end:\"/registry/roles/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T18:29:44.530795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.738754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-02T18:29:44.530845Z","caller":"traceutil/trace.go:171","msg":"trace[749712598] range","detail":"{range_begin:/registry/roles/default/; range_end:/registry/roles/default0; response_count:0; response_revision:1041; }","duration":"186.199043ms","start":"2026-03-02T18:29:44.344634Z","end":"2026-03-02T18:29:44.530833Z","steps":["trace[749712598] 'agreement among raft nodes before linearized reading' (duration: 186.104352ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:44.53086Z","caller":"traceutil/trace.go:171","msg":"trace[1050525557] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1041; }","duration":"230.817156ms","start":"2026-03-02T18:29:44.300028Z","end":"2026-03-02T18:29:44.530846Z","steps":["trace[1050525557] 'agreement among raft nodes before linearized reading' (duration: 230.638503ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:44.960945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.577327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-node-lease/\" range_end:\"/registry/roles/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:29:44.961034Z","caller":"traceutil/trace.go:171","msg":"trace[1625928023] range","detail":"{range_begin:/registry/roles/kube-node-lease/; range_end:/registry/roles/kube-node-lease0; response_count:0; response_revision:1042; }","duration":"341.701439ms","start":"2026-03-02T18:29:44.619314Z","end":"2026-03-02T18:29:44.961015Z","steps":["trace[1625928023] 'range keys from in-memory index tree' (duration: 341.466976ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:44.961083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:44.619308Z","time spent":"341.76384ms","remote":"127.0.0.1:57394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":27,"request content":"key:\"/registry/roles/kube-node-lease/\" range_end:\"/registry/roles/kube-node-lease0\" limit:500 "} {"level":"warn","ts":"2026-03-02T18:29:44.961247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.447821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T18:29:44.961223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.751148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T18:29:44.961262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.463971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:29:44.9613Z","caller":"traceutil/trace.go:171","msg":"trace[937536895] range","detail":"{range_begin:/registry/secrets/default/; range_end:/registry/secrets/default0; response_count:0; response_revision:1042; }","duration":"344.524442ms","start":"2026-03-02T18:29:44.616762Z","end":"2026-03-02T18:29:44.961287Z","steps":["trace[937536895] 'range keys from in-memory index tree' (duration: 344.40006ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:44.961324Z","caller":"traceutil/trace.go:171","msg":"trace[973039006] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1042; }","duration":"380.576442ms","start":"2026-03-02T18:29:44.580731Z","end":"2026-03-02T18:29:44.961307Z","steps":["trace[973039006] 'count revisions from in-memory index tree' (duration: 380.37473ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:44.961314Z","caller":"traceutil/trace.go:171","msg":"trace[1693724170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1042; }","duration":"149.876799ms","start":"2026-03-02T18:29:44.81142Z","end":"2026-03-02T18:29:44.961297Z","steps":["trace[1693724170] 'range keys from in-memory index tree' (duration: 149.690896ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:44.96133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:44.616749Z","time spent":"344.574633ms","remote":"127.0.0.1:57182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" limit:500 "} {"level":"warn","ts":"2026-03-02T18:29:44.961357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:44.580715Z","time spent":"380.635313ms","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-03-02T18:29:45.446332Z","caller":"traceutil/trace.go:171","msg":"trace[1139851076] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1088; }","duration":"253.477542ms","start":"2026-03-02T18:29:45.192833Z","end":"2026-03-02T18:29:45.44631Z","steps":["trace[1139851076] 'read index received' (duration: 253.185328ms)","trace[1139851076] 'applied index is now lower than readState.Index' (duration: 291.194µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:29:45.446492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.638914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-node-lease/default\" ","response":"range_response_count:1 size:187"} {"level":"info","ts":"2026-03-02T18:29:45.446545Z","caller":"traceutil/trace.go:171","msg":"trace[963085961] range","detail":"{range_begin:/registry/serviceaccounts/kube-node-lease/default; range_end:; response_count:1; response_revision:1043; }","duration":"253.716525ms","start":"2026-03-02T18:29:45.192806Z","end":"2026-03-02T18:29:45.446523Z","steps":["trace[963085961] 'agreement among raft nodes before linearized reading' (duration: 253.628454ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:45.446685Z","caller":"traceutil/trace.go:171","msg":"trace[114246110] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"259.207019ms","start":"2026-03-02T18:29:45.187445Z","end":"2026-03-02T18:29:45.446652Z","steps":["trace[114246110] 'process raft request' (duration: 258.715452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:45.44678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.653665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-03-02T18:29:45.446839Z","caller":"traceutil/trace.go:171","msg":"trace[1202303150] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:1043; }","duration":"205.726647ms","start":"2026-03-02T18:29:45.241092Z","end":"2026-03-02T18:29:45.446818Z","steps":["trace[1202303150] 'agreement among raft nodes before linearized reading' (duration: 205.651586ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:45.446959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.141342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-02T18:29:45.446704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.828773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-02T18:29:45.447Z","caller":"traceutil/trace.go:171","msg":"trace[1459956834] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:1043; }","duration":"127.214043ms","start":"2026-03-02T18:29:45.319773Z","end":"2026-03-02T18:29:45.446987Z","steps":["trace[1459956834] 'agreement among raft nodes before linearized reading' (duration: 127.124642ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:45.44702Z","caller":"traceutil/trace.go:171","msg":"trace[557691252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1043; }","duration":"180.175488ms","start":"2026-03-02T18:29:45.266834Z","end":"2026-03-02T18:29:45.44701Z","steps":["trace[557691252] 'agreement among raft nodes before linearized reading' (duration: 179.758851ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:45.446686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.710731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/default/\" range_end:\"/registry/services/specs/default0\" limit:500 ","response":"range_response_count:1 size:704"} {"level":"info","ts":"2026-03-02T18:29:45.447125Z","caller":"traceutil/trace.go:171","msg":"trace[72532577] range","detail":"{range_begin:/registry/services/specs/default/; range_end:/registry/services/specs/default0; response_count:1; response_revision:1043; }","duration":"232.202629ms","start":"2026-03-02T18:29:45.214913Z","end":"2026-03-02T18:29:45.447115Z","steps":["trace[72532577] 'agreement among raft nodes before linearized reading' (duration: 231.661361ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:45.447145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.036852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/kube-node-lease/\" range_end:\"/registry/statefulsets/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:29:45.447197Z","caller":"traceutil/trace.go:171","msg":"trace[871544631] range","detail":"{range_begin:/registry/statefulsets/kube-node-lease/; range_end:/registry/statefulsets/kube-node-lease0; response_count:0; response_revision:1043; }","duration":"208.086302ms","start":"2026-03-02T18:29:45.239094Z","end":"2026-03-02T18:29:45.447181Z","steps":["trace[871544631] 'agreement among raft nodes before linearized reading' (duration: 208.018591ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:45.574934Z","caller":"traceutil/trace.go:171","msg":"trace[63636277] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"124.37961ms","start":"2026-03-02T18:29:45.450528Z","end":"2026-03-02T18:29:45.574908Z","steps":["trace[63636277] 'process raft request' (duration: 124.137117ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:45.575111Z","caller":"traceutil/trace.go:171","msg":"trace[2122148417] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"124.253878ms","start":"2026-03-02T18:29:45.450837Z","end":"2026-03-02T18:29:45.57509Z","steps":["trace[2122148417] 'process raft request' (duration: 123.955734ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:46.793049Z","caller":"traceutil/trace.go:171","msg":"trace[1939244127] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"255.072947ms","start":"2026-03-02T18:29:46.537939Z","end":"2026-03-02T18:29:46.793012Z","steps":["trace[1939244127] 'process raft request' (duration: 254.834894ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:46.793117Z","caller":"traceutil/trace.go:171","msg":"trace[330919205] linearizableReadLoop","detail":"{readStateIndex:1094; appliedIndex:1093; }","duration":"253.436872ms","start":"2026-03-02T18:29:46.539659Z","end":"2026-03-02T18:29:46.793096Z","steps":["trace[330919205] 'read index received' (duration: 253.031596ms)","trace[330919205] 'applied index is now lower than readState.Index' (duration: 224.234µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:29:46.793252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.569994ms","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-03-02T18:29:46.793291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.669851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubelet-config\" ","response":"range_response_count:1 size:1357"} {"level":"info","ts":"2026-03-02T18:29:46.793309Z","caller":"traceutil/trace.go:171","msg":"trace[403078] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1047; }","duration":"253.639835ms","start":"2026-03-02T18:29:46.539655Z","end":"2026-03-02T18:29:46.793295Z","steps":["trace[403078] 'agreement among raft nodes before linearized reading' (duration: 253.444192ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:46.793317Z","caller":"traceutil/trace.go:171","msg":"trace[1281935497] range","detail":"{range_begin:/registry/configmaps/kube-system/kubelet-config; range_end:; response_count:1; response_revision:1047; }","duration":"252.739912ms","start":"2026-03-02T18:29:46.540569Z","end":"2026-03-02T18:29:46.793309Z","steps":["trace[1281935497] 'agreement among raft nodes before linearized reading' (duration: 252.66346ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:46.793426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.812966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14480"} {"level":"warn","ts":"2026-03-02T18:29:46.793441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.7636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-public/\" range_end:\"/registry/rolebindings/kube-public0\" limit:500 ","response":"range_response_count:2 size:1230"} {"level":"info","ts":"2026-03-02T18:29:46.793489Z","caller":"traceutil/trace.go:171","msg":"trace[931943755] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:1047; }","duration":"188.944558ms","start":"2026-03-02T18:29:46.604528Z","end":"2026-03-02T18:29:46.793473Z","steps":["trace[931943755] 'agreement among raft nodes before linearized reading' (duration: 188.744085ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:46.793505Z","caller":"traceutil/trace.go:171","msg":"trace[1712025437] range","detail":"{range_begin:/registry/rolebindings/kube-public/; range_end:/registry/rolebindings/kube-public0; response_count:2; response_revision:1047; }","duration":"230.874031ms","start":"2026-03-02T18:29:46.562611Z","end":"2026-03-02T18:29:46.793485Z","steps":["trace[1712025437] 'agreement among raft nodes before linearized reading' (duration: 230.724059ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:47.061192Z","caller":"traceutil/trace.go:171","msg":"trace[1834608794] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1094; }","duration":"251.635611ms","start":"2026-03-02T18:29:46.80953Z","end":"2026-03-02T18:29:47.061166Z","steps":["trace[1834608794] 'read index received' (duration: 158.993854ms)","trace[1834608794] 'applied index is now lower than readState.Index' (duration: 92.640407ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:29:47.061278Z","caller":"traceutil/trace.go:171","msg":"trace[1750452851] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"261.852326ms","start":"2026-03-02T18:29:46.799391Z","end":"2026-03-02T18:29:47.061243Z","steps":["trace[1750452851] 'process raft request' (duration: 169.223839ms)","trace[1750452851] 'compare' (duration: 92.366343ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:29:47.061401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.853644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:29:47.061452Z","caller":"traceutil/trace.go:171","msg":"trace[889637682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1048; }","duration":"251.947516ms","start":"2026-03-02T18:29:46.80949Z","end":"2026-03-02T18:29:47.061438Z","steps":["trace[889637682] 'agreement among raft nodes before linearized reading' (duration: 251.846205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:47.061966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.069479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubelet-config\" ","response":"range_response_count:1 size:1357"} {"level":"info","ts":"2026-03-02T18:29:47.062018Z","caller":"traceutil/trace.go:171","msg":"trace[637945013] range","detail":"{range_begin:/registry/configmaps/kube-system/kubelet-config; range_end:; response_count:1; response_revision:1048; }","duration":"182.143091ms","start":"2026-03-02T18:29:46.879862Z","end":"2026-03-02T18:29:47.062005Z","steps":["trace[637945013] 'agreement among raft nodes before linearized reading' (duration: 182.029229ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:47.062225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.073774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-system/\" range_end:\"/registry/persistentvolumeclaims/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:29:47.062256Z","caller":"traceutil/trace.go:171","msg":"trace[881630326] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-system/; range_end:/registry/persistentvolumeclaims/kube-system0; response_count:0; response_revision:1048; }","duration":"177.127494ms","start":"2026-03-02T18:29:46.885119Z","end":"2026-03-02T18:29:47.062247Z","steps":["trace[881630326] 'agreement among raft nodes before linearized reading' (duration: 177.070604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:47.062288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.213248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T18:29:47.062375Z","caller":"traceutil/trace.go:171","msg":"trace[382813943] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1048; }","duration":"154.35398ms","start":"2026-03-02T18:29:46.907998Z","end":"2026-03-02T18:29:47.062352Z","steps":["trace[382813943] 'agreement among raft nodes before linearized reading' (duration: 154.188588ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:47.062816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.907667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14480"} {"level":"info","ts":"2026-03-02T18:29:47.062859Z","caller":"traceutil/trace.go:171","msg":"trace[1240614231] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:1048; }","duration":"179.952288ms","start":"2026-03-02T18:29:46.882897Z","end":"2026-03-02T18:29:47.062849Z","steps":["trace[1240614231] 'agreement among raft nodes before linearized reading' (duration: 179.48356ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:49.817355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.923602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler\" ","response":"range_response_count:1 size:808"} {"level":"warn","ts":"2026-03-02T18:29:49.817368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.059318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::leader-locking-kube-controller-manager\" ","response":"range_response_count:1 size:853"} {"level":"info","ts":"2026-03-02T18:29:49.817457Z","caller":"traceutil/trace.go:171","msg":"trace[524695290] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler; range_end:; response_count:1; response_revision:1057; }","duration":"184.046744ms","start":"2026-03-02T18:29:49.63339Z","end":"2026-03-02T18:29:49.817437Z","steps":["trace[524695290] 'range keys from in-memory index tree' (duration: 183.86484ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:49.81756Z","caller":"traceutil/trace.go:171","msg":"trace[781669975] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:1; response_revision:1057; }","duration":"187.18607ms","start":"2026-03-02T18:29:49.630273Z","end":"2026-03-02T18:29:49.817459Z","steps":["trace[781669975] 'range keys from in-memory index tree' (duration: 186.989218ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:51.240759Z","caller":"traceutil/trace.go:171","msg":"trace[516593838] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"100.612565ms","start":"2026-03-02T18:29:51.14012Z","end":"2026-03-02T18:29:51.240733Z","steps":["trace[516593838] 'process raft request' (duration: 100.449673ms)"],"step_count":1}