{"level":"warn","ts":"2026-08-24T15:44:30.04539Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:44:30.045471Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.81:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.81:2380","--initial-cluster=instance=https://199.19.213.81:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.81:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.81: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-08-24T15:44:30.045528Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:44:30.045542Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.81:2380"]} {"level":"info","ts":"2026-08-24T15:44:30.04556Z","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-08-24T15:44:30.046244Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.81:2379"]} {"level":"info","ts":"2026-08-24T15:44:30.046315Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.81:2380"],"listen-peer-urls":["https://199.19.213.81:2380"],"advertise-client-urls":["https://199.19.213.81:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.81:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.81: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-08-24T15:44:30.049179Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.557759ms"} {"level":"info","ts":"2026-08-24T15:44:30.055709Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1627b0b72e417489","cluster-id":"d42e5eae8a1b814c"} {"level":"info","ts":"2026-08-24T15:44:30.055777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T15:44:30.055795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became follower at term 0"} {"level":"info","ts":"2026-08-24T15:44:30.055807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1627b0b72e417489 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T15:44:30.055818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became follower at term 1"} {"level":"info","ts":"2026-08-24T15:44:30.055852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 switched to configuration voters=(1596438893727675529)"} {"level":"warn","ts":"2026-08-24T15:44:30.06507Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T15:44:30.066699Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T15:44:30.068495Z","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-08-24T15:44:30.070835Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1627b0b72e417489","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T15:44:30.073839Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1627b0b72e417489","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T15:44:30.073903Z","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-08-24T15:44:30.074007Z","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-08-24T15:44:30.07403Z","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-08-24T15:44:30.078044Z","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-08-24T15:44:30.078202Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.81:2380"} {"level":"info","ts":"2026-08-24T15:44:30.078239Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.81:2380"} {"level":"info","ts":"2026-08-24T15:44:30.078283Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1627b0b72e417489","initial-advertise-peer-urls":["https://199.19.213.81:2380"],"listen-peer-urls":["https://199.19.213.81:2380"],"advertise-client-urls":["https://199.19.213.81:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.81:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T15:44:30.078324Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T15:44:30.080982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 switched to configuration voters=(1596438893727675529)"} {"level":"info","ts":"2026-08-24T15:44:30.081104Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d42e5eae8a1b814c","local-member-id":"1627b0b72e417489","added-peer-id":"1627b0b72e417489","added-peer-peer-urls":["https://199.19.213.81:2380"]} {"level":"info","ts":"2026-08-24T15:44:30.256482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T15:44:30.256575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T15:44:30.256601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 received MsgPreVoteResp from 1627b0b72e417489 at term 1"} {"level":"info","ts":"2026-08-24T15:44:30.256623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became candidate at term 2"} {"level":"info","ts":"2026-08-24T15:44:30.256634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 received MsgVoteResp from 1627b0b72e417489 at term 2"} {"level":"info","ts":"2026-08-24T15:44:30.25665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1627b0b72e417489 became leader at term 2"} {"level":"info","ts":"2026-08-24T15:44:30.25667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1627b0b72e417489 elected leader 1627b0b72e417489 at term 2"} {"level":"info","ts":"2026-08-24T15:44:30.257935Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:44:30.258907Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1627b0b72e417489","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.81:2379]}","request-path":"/0/members/1627b0b72e417489/attributes","cluster-id":"d42e5eae8a1b814c","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T15:44:30.258915Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:44:30.259062Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:44:30.259281Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T15:44:30.259331Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T15:44:30.259656Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d42e5eae8a1b814c","local-member-id":"1627b0b72e417489","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:44:30.259811Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:44:30.259864Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:44:30.263837Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-24T15:44:30.263851Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.81:2379"} {"level":"info","ts":"2026-08-24T15:44:47.094204Z","caller":"traceutil/trace.go:171","msg":"trace[72961524] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"376.505818ms","start":"2026-08-24T15:44:46.717648Z","end":"2026-08-24T15:44:47.094154Z","steps":["trace[72961524] 'process raft request' (duration: 376.228971ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:44:47.09529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:44:46.717626Z","time spent":"376.796765ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:44:48.157932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.654266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-24T15:44:48.158083Z","caller":"traceutil/trace.go:171","msg":"trace[1148192093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:412; }","duration":"304.848591ms","start":"2026-08-24T15:44:47.853212Z","end":"2026-08-24T15:44:48.15806Z","steps":["trace[1148192093] 'range keys from in-memory index tree' (duration: 304.557174ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:44:48.158082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.30191ms","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-08-24T15:44:48.158218Z","caller":"traceutil/trace.go:171","msg":"trace[2076713926] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:412; }","duration":"153.507395ms","start":"2026-08-24T15:44:48.004682Z","end":"2026-08-24T15:44:48.158189Z","steps":["trace[2076713926] 'range keys from in-memory index tree' (duration: 153.061085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:44:48.158125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:44:47.853193Z","time spent":"304.921562ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:44:48.157932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.556142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-08-24T15:44:48.15869Z","caller":"traceutil/trace.go:171","msg":"trace[1581953203] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:412; }","duration":"593.333279ms","start":"2026-08-24T15:44:47.565327Z","end":"2026-08-24T15:44:48.15866Z","steps":["trace[1581953203] 'range keys from in-memory index tree' (duration: 592.403798ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:44:48.158783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:44:47.565308Z","time spent":"593.441812ms","remote":"127.0.0.1:45990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6984,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-08-24T15:45:08.533572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.020652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:10089"} {"level":"info","ts":"2026-08-24T15:45:08.53382Z","caller":"traceutil/trace.go:171","msg":"trace[922941331] transaction","detail":"{read_only:false; number_of_response:0; response_revision:546; }","duration":"1.131113329s","start":"2026-08-24T15:45:07.402692Z","end":"2026-08-24T15:45:08.533806Z","steps":["trace[922941331] 'process raft request' (duration: 1.131083589s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:08.533886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:07.402678Z","time spent":"1.131183572s","remote":"127.0.0.1:34718","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:45:08.533997Z","caller":"traceutil/trace.go:171","msg":"trace[1945118264] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.134775803s","start":"2026-08-24T15:45:07.399191Z","end":"2026-08-24T15:45:08.533966Z","steps":["trace[1945118264] 'process raft request' (duration: 1.134491087s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:08.53399Z","caller":"traceutil/trace.go:171","msg":"trace[1393929942] transaction","detail":"{read_only:false; number_of_response:1; response_revision:545; }","duration":"1.134767413s","start":"2026-08-24T15:45:07.399184Z","end":"2026-08-24T15:45:08.533951Z","steps":["trace[1393929942] 'process raft request' (duration: 199.146747ms)","trace[1393929942] 'compare' (duration: 934.9408ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:45:08.534156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:07.399178Z","time spent":"1.134888235s","remote":"127.0.0.1:45860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:45:08.534176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:07.399162Z","time spent":"1.134915396s","remote":"127.0.0.1:45860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:45:08.699702Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.100635394s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:45:08.718406Z","caller":"traceutil/trace.go:171","msg":"trace[1910963108] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"1.30978746s","start":"2026-08-24T15:45:07.408563Z","end":"2026-08-24T15:45:08.71835Z","steps":["trace[1910963108] 'process raft request' (duration: 1.29143668s)","trace[1910963108] 'compare' (duration: 17.370057ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:45:08.718717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:07.408547Z","time spent":"1.309949264s","remote":"127.0.0.1:45860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:45:08.719574Z","caller":"traceutil/trace.go:171","msg":"trace[416651237] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"1.307286073s","start":"2026-08-24T15:45:07.412264Z","end":"2026-08-24T15:45:08.71955Z","steps":["trace[416651237] 'process raft request' (duration: 1.305636146s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:08.719703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:07.412247Z","time spent":"1.307401486s","remote":"127.0.0.1:45860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:45:08.72242Z","caller":"traceutil/trace.go:171","msg":"trace[2001734311] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.303005965s","start":"2026-08-24T15:45:07.418332Z","end":"2026-08-24T15:45:08.721338Z","steps":["trace[2001734311] 'process raft request' (duration: 1.3006048s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:08.72251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:07.418316Z","time spent":"1.30414692s","remote":"127.0.0.1:45860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:45:08.723135Z","caller":"traceutil/trace.go:171","msg":"trace[709599955] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.282311702s","start":"2026-08-24T15:45:07.440811Z","end":"2026-08-24T15:45:08.723123Z","steps":["trace[709599955] 'process raft request' (duration: 1.282144628s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:08.7232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:07.439916Z","time spent":"1.283253514s","remote":"127.0.0.1:45860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:45:08.723281Z","caller":"traceutil/trace.go:171","msg":"trace[630770013] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"1.295121664s","start":"2026-08-24T15:45:07.42815Z","end":"2026-08-24T15:45:08.723272Z","steps":["trace[630770013] 'process raft request' (duration: 1.293036858s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:08.723333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:07.428128Z","time spent":"1.295179596s","remote":"127.0.0.1:45860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:45:08.723769Z","caller":"traceutil/trace.go:171","msg":"trace[848292494] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.202228575s","start":"2026-08-24T15:45:07.521511Z","end":"2026-08-24T15:45:08.723739Z","steps":["trace[848292494] 'process raft request' (duration: 1.201558079s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:08.723807Z","caller":"traceutil/trace.go:171","msg":"trace[989030715] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:568; }","duration":"466.471033ms","start":"2026-08-24T15:45:08.25728Z","end":"2026-08-24T15:45:08.723752Z","steps":["trace[989030715] 'read index received' (duration: 442.746501ms)","trace[989030715] 'applied index is now lower than readState.Index' (duration: 23.721962ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:45:08.723904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:07.521482Z","time spent":"1.202365447s","remote":"127.0.0.1:45860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:45:08.723928Z","caller":"traceutil/trace.go:171","msg":"trace[244045078] transaction","detail":"{read_only:false; number_of_response:1; response_revision:552; }","duration":"181.568986ms","start":"2026-08-24T15:45:08.542347Z","end":"2026-08-24T15:45:08.723916Z","steps":["trace[244045078] 'process raft request' (duration: 181.30934ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:08.724044Z","caller":"traceutil/trace.go:171","msg":"trace[2106781646] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"177.143705ms","start":"2026-08-24T15:45:08.54689Z","end":"2026-08-24T15:45:08.724034Z","steps":["trace[2106781646] 'process raft request' (duration: 176.819907ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:08.7317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.411363ms","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-08-24T15:45:08.731772Z","caller":"traceutil/trace.go:171","msg":"trace[2064532571] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"474.488756ms","start":"2026-08-24T15:45:08.257265Z","end":"2026-08-24T15:45:08.731754Z","steps":["trace[2064532571] 'agreement among raft nodes before linearized reading' (duration: 466.618306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:08.731815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:08.257202Z","time spent":"474.603948ms","remote":"127.0.0.1:34442","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-08-24T15:45:08.731839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.864917ms","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-08-24T15:45:08.731943Z","caller":"traceutil/trace.go:171","msg":"trace[1709084417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"462.051691ms","start":"2026-08-24T15:45:08.269871Z","end":"2026-08-24T15:45:08.731923Z","steps":["trace[1709084417] 'agreement among raft nodes before linearized reading' (duration: 461.819315ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:08.731992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:08.269849Z","time spent":"462.133223ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:45:08.732316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.562281ms","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-08-24T15:45:08.732376Z","caller":"traceutil/trace.go:171","msg":"trace[1057696507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:553; }","duration":"454.634313ms","start":"2026-08-24T15:45:08.277726Z","end":"2026-08-24T15:45:08.73236Z","steps":["trace[1057696507] 'agreement among raft nodes before linearized reading' (duration: 454.555371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:08.73241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:08.277713Z","time spent":"454.689634ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:45:08.732553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.955049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-08-24T15:45:08.732581Z","caller":"traceutil/trace.go:171","msg":"trace[1786872517] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:553; }","duration":"197.02389ms","start":"2026-08-24T15:45:08.535549Z","end":"2026-08-24T15:45:08.732573Z","steps":["trace[1786872517] 'agreement among raft nodes before linearized reading' (duration: 196.886746ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:14.34239Z","caller":"traceutil/trace.go:171","msg":"trace[624393773] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"562.506515ms","start":"2026-08-24T15:45:13.779866Z","end":"2026-08-24T15:45:14.342373Z","steps":["trace[624393773] 'process raft request' (duration: 562.28795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:14.342496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:13.779843Z","time spent":"562.610457ms","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:45:15.941909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.818211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:45:15.942129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.604846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bl8mt\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-08-24T15:45:15.942181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.367869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-24T15:45:15.942191Z","caller":"traceutil/trace.go:171","msg":"trace[1957464851] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bl8mt; range_end:; response_count:1; response_revision:626; }","duration":"710.717539ms","start":"2026-08-24T15:45:15.23146Z","end":"2026-08-24T15:45:15.942178Z","steps":["trace[1957464851] 'range keys from in-memory index tree' (duration: 710.413572ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:15.942221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:15.231432Z","time spent":"710.78207ms","remote":"127.0.0.1:45990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-bl8mt\" "} {"level":"info","ts":"2026-08-24T15:45:15.942215Z","caller":"traceutil/trace.go:171","msg":"trace[1623247581] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"709.402979ms","start":"2026-08-24T15:45:15.232803Z","end":"2026-08-24T15:45:15.942206Z","steps":["trace[1623247581] 'range keys from in-memory index tree' (duration: 709.287437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:15.942248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:15.232777Z","time spent":"709.46474ms","remote":"127.0.0.1:34442","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-08-24T15:45:15.941996Z","caller":"traceutil/trace.go:171","msg":"trace[1518872432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"925.947743ms","start":"2026-08-24T15:45:15.01603Z","end":"2026-08-24T15:45:15.941978Z","steps":["trace[1518872432] 'range keys from in-memory index tree' (duration: 925.699069ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:15.942623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:15.016013Z","time spent":"926.60147ms","remote":"127.0.0.1:45802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T15:45:15.964893Z","caller":"traceutil/trace.go:171","msg":"trace[955877809] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"641.061058ms","start":"2026-08-24T15:45:15.323787Z","end":"2026-08-24T15:45:15.964848Z","steps":["trace[955877809] 'process raft request' (duration: 637.496127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:15.965051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:15.323757Z","time spent":"641.211393ms","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:45:16.193698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.639023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:45:16.194393Z","caller":"traceutil/trace.go:171","msg":"trace[1358109371] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"248.79319ms","start":"2026-08-24T15:45:15.945563Z","end":"2026-08-24T15:45:16.194356Z","steps":["trace[1358109371] 'process raft request' (duration: 131.420651ms)","trace[1358109371] 'compare' (duration: 116.432968ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:45:16.195112Z","caller":"traceutil/trace.go:171","msg":"trace[1481121461] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:652; }","duration":"180.279077ms","start":"2026-08-24T15:45:16.014819Z","end":"2026-08-24T15:45:16.195098Z","steps":["trace[1481121461] 'read index received' (duration: 62.15735ms)","trace[1481121461] 'applied index is now lower than readState.Index' (duration: 118.119897ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:45:16.195262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.433971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:45:16.195304Z","caller":"traceutil/trace.go:171","msg":"trace[1057989680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"180.525573ms","start":"2026-08-24T15:45:16.014767Z","end":"2026-08-24T15:45:16.195293Z","steps":["trace[1057989680] 'agreement among raft nodes before linearized reading' (duration: 180.3852ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:16.195476Z","caller":"traceutil/trace.go:171","msg":"trace[75249902] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"179.798655ms","start":"2026-08-24T15:45:16.015667Z","end":"2026-08-24T15:45:16.195466Z","steps":["trace[75249902] 'process raft request' (duration: 179.218362ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:16.195095Z","caller":"traceutil/trace.go:171","msg":"trace[1294480610] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"238.924967ms","start":"2026-08-24T15:45:15.956138Z","end":"2026-08-24T15:45:16.195063Z","steps":["trace[1294480610] 'process raft request' (duration: 238.131328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:16.698705Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397419127317425290,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:45:17.199688Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397419127317425290,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:45:17.699847Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397419127317425290,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:45:18.021846Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.846471797s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-24T15:45:18.196745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.998922067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T15:45:18.196828Z","caller":"traceutil/trace.go:171","msg":"trace[262174171] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999039169s","start":"2026-08-24T15:45:16.197768Z","end":"2026-08-24T15:45:18.196807Z","steps":["trace[262174171] 'agreement among raft nodes before linearized reading' (duration: 1.998941667s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:18.196882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:16.197755Z","time spent":"1.999115831s","remote":"127.0.0.1:45802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/24 15:45:18 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:45:19.245964Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.038175708s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-24T15:45:19.251462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229378723s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:45:19.251854Z","caller":"traceutil/trace.go:171","msg":"trace[1322327723] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"3.085135061s","start":"2026-08-24T15:45:16.166694Z","end":"2026-08-24T15:45:19.251829Z","steps":["trace[1322327723] 'process raft request' (duration: 1.855331888s)","trace[1322327723] 'compare' (duration: 1.22885023s)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:45:19.251994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:16.166665Z","time spent":"3.085254994s","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:45:19.252274Z","caller":"traceutil/trace.go:171","msg":"trace[728427110] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.044574143s","start":"2026-08-24T15:45:18.207687Z","end":"2026-08-24T15:45:19.252261Z","steps":["trace[728427110] 'process raft request' (duration: 1.043841737s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:19.25233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:18.207672Z","time spent":"1.044629405s","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:45:19.252295Z","caller":"traceutil/trace.go:171","msg":"trace[1279224462] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"3.05446508s","start":"2026-08-24T15:45:16.197794Z","end":"2026-08-24T15:45:19.252259Z","steps":["trace[1279224462] 'read index received' (duration: 1.824171216s)","trace[1279224462] 'applied index is now lower than readState.Index' (duration: 1.230290734s)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:45:19.269224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.063806715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:45:19.269545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071324574s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-24T15:45:19.269908Z","caller":"traceutil/trace.go:171","msg":"trace[554364228] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"1.071739234s","start":"2026-08-24T15:45:18.198154Z","end":"2026-08-24T15:45:19.269893Z","steps":["trace[554364228] 'agreement among raft nodes before linearized reading' (duration: 1.071083029s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:19.269973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:18.198137Z","time spent":"1.071819925s","remote":"127.0.0.1:34442","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-08-24T15:45:19.269585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.451251876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-24T15:45:19.270179Z","caller":"traceutil/trace.go:171","msg":"trace[1112346669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"2.45187142s","start":"2026-08-24T15:45:16.818298Z","end":"2026-08-24T15:45:19.270169Z","steps":["trace[1112346669] 'agreement among raft nodes before linearized reading' (duration: 2.451226785s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:19.270221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:16.818286Z","time spent":"2.451923161s","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:45:19.269567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.452869443s","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-08-24T15:45:19.270311Z","caller":"traceutil/trace.go:171","msg":"trace[1228334983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"2.45367818s","start":"2026-08-24T15:45:16.816625Z","end":"2026-08-24T15:45:19.270303Z","steps":["trace[1228334983] 'agreement among raft nodes before linearized reading' (duration: 2.452812421s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:19.270346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:16.816602Z","time spent":"2.453736942s","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:45:19.269633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061077331s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:45:19.270429Z","caller":"traceutil/trace.go:171","msg":"trace[1568075486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"1.06191704s","start":"2026-08-24T15:45:18.208504Z","end":"2026-08-24T15:45:19.270421Z","steps":["trace[1568075486] 'agreement among raft nodes before linearized reading' (duration: 1.061096181s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:19.270466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:18.208482Z","time spent":"1.061976281s","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:45:19.269724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.46064307s","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-08-24T15:45:19.269819Z","caller":"traceutil/trace.go:171","msg":"trace[1628398915] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:632; }","duration":"3.064422589s","start":"2026-08-24T15:45:16.205375Z","end":"2026-08-24T15:45:19.269798Z","steps":["trace[1628398915] 'agreement among raft nodes before linearized reading' (duration: 3.063798525s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:19.270657Z","caller":"traceutil/trace.go:171","msg":"trace[232354586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"2.461588832s","start":"2026-08-24T15:45:16.809039Z","end":"2026-08-24T15:45:19.270628Z","steps":["trace[232354586] 'agreement among raft nodes before linearized reading' (duration: 2.460589559s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:19.270741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:16.809018Z","time spent":"2.461701495s","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-24T15:45:19.270751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:16.205334Z","time spent":"3.065383701s","remote":"127.0.0.1:34528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-08-24T15:45:20.926768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.818426ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:45:20.926877Z","caller":"traceutil/trace.go:171","msg":"trace[1834894820] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:644; }","duration":"482.945359ms","start":"2026-08-24T15:45:20.443916Z","end":"2026-08-24T15:45:20.926861Z","steps":["trace[1834894820] 'range keys from in-memory index tree' (duration: 482.801716ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:20.927636Z","caller":"traceutil/trace.go:171","msg":"trace[1245706493] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"187.206545ms","start":"2026-08-24T15:45:20.740407Z","end":"2026-08-24T15:45:20.927614Z","steps":["trace[1245706493] 'process raft request' (duration: 185.69422ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:31.704371Z","caller":"traceutil/trace.go:171","msg":"trace[365530374] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"359.363826ms","start":"2026-08-24T15:45:31.344981Z","end":"2026-08-24T15:45:31.704345Z","steps":["trace[365530374] 'process raft request' (duration: 359.195342ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:31.704531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:31.344905Z","time spent":"359.54437ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:45:31.851448Z","caller":"traceutil/trace.go:171","msg":"trace[326149120] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"503.910506ms","start":"2026-08-24T15:45:31.347516Z","end":"2026-08-24T15:45:31.851427Z","steps":["trace[326149120] 'process raft request' (duration: 503.834024ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:31.85159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:31.347498Z","time spent":"504.01746ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:45:31.851763Z","caller":"traceutil/trace.go:171","msg":"trace[1385934009] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:805; }","duration":"485.106289ms","start":"2026-08-24T15:45:31.366592Z","end":"2026-08-24T15:45:31.851699Z","steps":["trace[1385934009] 'read index received' (duration: 337.631341ms)","trace[1385934009] 'applied index is now lower than readState.Index' (duration: 147.473498ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:45:31.851962Z","caller":"traceutil/trace.go:171","msg":"trace[596837186] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"503.050708ms","start":"2026-08-24T15:45:31.348858Z","end":"2026-08-24T15:45:31.851908Z","steps":["trace[596837186] 'process raft request' (duration: 502.535465ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:31.851824Z","caller":"traceutil/trace.go:171","msg":"trace[1217996497] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"506.584349ms","start":"2026-08-24T15:45:31.345229Z","end":"2026-08-24T15:45:31.851814Z","steps":["trace[1217996497] 'process raft request' (duration: 503.939088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:31.852257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.672082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-q6vmr\" ","response":"range_response_count:1 size:4740"} {"level":"warn","ts":"2026-08-24T15:45:31.852223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:31.348834Z","time spent":"503.219812ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:45:31.852535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:31.345205Z","time spent":"506.902325ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:45:31.852304Z","caller":"traceutil/trace.go:171","msg":"trace[1313422659] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-q6vmr; range_end:; response_count:1; response_revision:780; }","duration":"485.760504ms","start":"2026-08-24T15:45:31.366531Z","end":"2026-08-24T15:45:31.852291Z","steps":["trace[1313422659] 'agreement among raft nodes before linearized reading' (duration: 485.60237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:31.852666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:31.366508Z","time spent":"486.09283ms","remote":"127.0.0.1:45990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4763,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-q6vmr\" "} {"level":"warn","ts":"2026-08-24T15:45:35.138079Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397419127317425618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:45:35.585886Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.218131788s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:45:35.586324Z","caller":"traceutil/trace.go:171","msg":"trace[6068448] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"948.986072ms","start":"2026-08-24T15:45:34.637311Z","end":"2026-08-24T15:45:35.586298Z","steps":["trace[6068448] 'read index received' (duration: 948.733436ms)","trace[6068448] 'applied index is now lower than readState.Index' (duration: 251.546µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:45:35.586379Z","caller":"traceutil/trace.go:171","msg":"trace[3243308] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"1.218722631s","start":"2026-08-24T15:45:34.367639Z","end":"2026-08-24T15:45:35.586362Z","steps":["trace[3243308] 'process raft request' (duration: 1.218486526s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:35.5865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:34.367622Z","time spent":"1.218794932s","remote":"127.0.0.1:45990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:45:35.586594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.280188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:45:35.586899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.283669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-24T15:45:35.587366Z","caller":"traceutil/trace.go:171","msg":"trace[1852781052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:804; }","duration":"371.804501ms","start":"2026-08-24T15:45:35.215534Z","end":"2026-08-24T15:45:35.587338Z","steps":["trace[1852781052] 'agreement among raft nodes before linearized reading' (duration: 371.048094ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:35.587552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:35.215512Z","time spent":"372.023915ms","remote":"127.0.0.1:34442","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-08-24T15:45:35.586678Z","caller":"traceutil/trace.go:171","msg":"trace[1717086536] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:804; }","duration":"949.405901ms","start":"2026-08-24T15:45:34.637257Z","end":"2026-08-24T15:45:35.586663Z","steps":["trace[1717086536] 'agreement among raft nodes before linearized reading' (duration: 949.169585ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:35.588354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:34.637237Z","time spent":"951.093599ms","remote":"127.0.0.1:45958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:45:35.58714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.705549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:45:35.588852Z","caller":"traceutil/trace.go:171","msg":"trace[1734407655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"571.44672ms","start":"2026-08-24T15:45:35.017382Z","end":"2026-08-24T15:45:35.588829Z","steps":["trace[1734407655] 'agreement among raft nodes before linearized reading' (duration: 569.147347ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:35.589648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:35.017361Z","time spent":"572.272169ms","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T15:45:36.007343Z","caller":"traceutil/trace.go:171","msg":"trace[438574479] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:835; }","duration":"282.730637ms","start":"2026-08-24T15:45:35.724583Z","end":"2026-08-24T15:45:36.007314Z","steps":["trace[438574479] 'read index received' (duration: 263.684501ms)","trace[438574479] 'applied index is now lower than readState.Index' (duration: 19.044206ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:45:36.007409Z","caller":"traceutil/trace.go:171","msg":"trace[1170139399] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"414.552597ms","start":"2026-08-24T15:45:35.592818Z","end":"2026-08-24T15:45:36.007371Z","steps":["trace[1170139399] 'process raft request' (duration: 395.45266ms)","trace[1170139399] 'compare' (duration: 18.550544ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:45:36.007606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:35.592805Z","time spent":"414.71145ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:45:36.007633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.038953ms","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-08-24T15:45:36.007658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.966439ms","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-08-24T15:45:36.007677Z","caller":"traceutil/trace.go:171","msg":"trace[387543708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:805; }","duration":"283.132745ms","start":"2026-08-24T15:45:35.724532Z","end":"2026-08-24T15:45:36.007665Z","steps":["trace[387543708] 'agreement among raft nodes before linearized reading' (duration: 282.940051ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:36.007725Z","caller":"traceutil/trace.go:171","msg":"trace[362605256] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:805; }","duration":"141.069471ms","start":"2026-08-24T15:45:35.866636Z","end":"2026-08-24T15:45:36.007705Z","steps":["trace[362605256] 'agreement among raft nodes before linearized reading' (duration: 140.897357ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:36.007724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.617388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-24T15:45:36.007792Z","caller":"traceutil/trace.go:171","msg":"trace[1297411348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"139.702131ms","start":"2026-08-24T15:45:35.868068Z","end":"2026-08-24T15:45:36.00777Z","steps":["trace[1297411348] 'agreement among raft nodes before linearized reading' (duration: 139.499986ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:36.007882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.853514ms","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-08-24T15:45:36.007927Z","caller":"traceutil/trace.go:171","msg":"trace[704687506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"139.933915ms","start":"2026-08-24T15:45:35.867984Z","end":"2026-08-24T15:45:36.007918Z","steps":["trace[704687506] 'agreement among raft nodes before linearized reading' (duration: 139.821713ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:50.173911Z","caller":"traceutil/trace.go:171","msg":"trace[161745683] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"355.1072ms","start":"2026-08-24T15:45:49.818785Z","end":"2026-08-24T15:45:50.173892Z","steps":["trace[161745683] 'read index received' (duration: 354.966477ms)","trace[161745683] 'applied index is now lower than readState.Index' (duration: 139.823µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:45:50.173944Z","caller":"traceutil/trace.go:171","msg":"trace[299593024] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"638.807976ms","start":"2026-08-24T15:45:49.535111Z","end":"2026-08-24T15:45:50.173919Z","steps":["trace[299593024] 'process raft request' (duration: 638.639382ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:50.174087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.287174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-2m9jb\" ","response":"range_response_count:1 size:5676"} {"level":"warn","ts":"2026-08-24T15:45:50.174118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.140062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:45:50.174135Z","caller":"traceutil/trace.go:171","msg":"trace[1014629186] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-2m9jb; range_end:; response_count:1; response_revision:935; }","duration":"355.336855ms","start":"2026-08-24T15:45:49.818779Z","end":"2026-08-24T15:45:50.174116Z","steps":["trace[1014629186] 'agreement among raft nodes before linearized reading' (duration: 355.195972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:50.174174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:49.81873Z","time spent":"355.437447ms","remote":"127.0.0.1:45990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5699,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-2m9jb\" "} {"level":"info","ts":"2026-08-24T15:45:50.174177Z","caller":"traceutil/trace.go:171","msg":"trace[1560800319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"158.199494ms","start":"2026-08-24T15:45:50.01596Z","end":"2026-08-24T15:45:50.17416Z","steps":["trace[1560800319] 'agreement among raft nodes before linearized reading' (duration: 158.115392ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:50.174121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:49.535076Z","time spent":"638.98466ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:45:50.779562Z","caller":"traceutil/trace.go:171","msg":"trace[51449601] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"601.294368ms","start":"2026-08-24T15:45:50.178246Z","end":"2026-08-24T15:45:50.77954Z","steps":["trace[51449601] 'process raft request' (duration: 601.162425ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:50.779711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:50.178235Z","time spent":"601.39723ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:45:50.818855Z","caller":"traceutil/trace.go:171","msg":"trace[1935628387] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:969; }","duration":"310.74796ms","start":"2026-08-24T15:45:50.508092Z","end":"2026-08-24T15:45:50.81884Z","steps":["trace[1935628387] 'read index received' (duration: 271.25534ms)","trace[1935628387] 'applied index is now lower than readState.Index' (duration: 39.49196ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:45:50.818962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.848852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:45:50.818923Z","caller":"traceutil/trace.go:171","msg":"trace[901592684] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"640.364879ms","start":"2026-08-24T15:45:50.178523Z","end":"2026-08-24T15:45:50.818888Z","steps":["trace[901592684] 'process raft request' (duration: 640.236656ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:50.819034Z","caller":"traceutil/trace.go:171","msg":"trace[150888277] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"323.250824ms","start":"2026-08-24T15:45:50.49578Z","end":"2026-08-24T15:45:50.819031Z","steps":["trace[150888277] 'process raft request' (duration: 323.037989ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:50.819094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:50.495762Z","time spent":"323.294175ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:45:50.819139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:50.178514Z","time spent":"640.535662ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:45:50.819137Z","caller":"traceutil/trace.go:171","msg":"trace[222618129] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"640.526542ms","start":"2026-08-24T15:45:50.17859Z","end":"2026-08-24T15:45:50.819117Z","steps":["trace[222618129] 'process raft request' (duration: 640.183074ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:50.819257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:50.178582Z","time spent":"640.618245ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:45:50.81937Z","caller":"traceutil/trace.go:171","msg":"trace[1153146109] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"640.937491ms","start":"2026-08-24T15:45:50.178421Z","end":"2026-08-24T15:45:50.819359Z","steps":["trace[1153146109] 'process raft request' (duration: 640.231945ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:50.819434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:50.178399Z","time spent":"641.004093ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:45:50.819507Z","caller":"traceutil/trace.go:171","msg":"trace[1562979599] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"641.045323ms","start":"2026-08-24T15:45:50.178455Z","end":"2026-08-24T15:45:50.819501Z","steps":["trace[1562979599] 'process raft request' (duration: 640.285146ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:50.81898Z","caller":"traceutil/trace.go:171","msg":"trace[919005361] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:942; }","duration":"310.907374ms","start":"2026-08-24T15:45:50.508067Z","end":"2026-08-24T15:45:50.818975Z","steps":["trace[919005361] 'agreement among raft nodes before linearized reading' (duration: 310.839282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:50.81955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:50.178446Z","time spent":"641.085175ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:45:50.819556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:50.508058Z","time spent":"311.487376ms","remote":"127.0.0.1:34418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":1,"response size":30,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-08-24T15:45:50.81902Z","caller":"traceutil/trace.go:171","msg":"trace[1018918189] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"636.622614ms","start":"2026-08-24T15:45:50.182392Z","end":"2026-08-24T15:45:50.819014Z","steps":["trace[1018918189] 'process raft request' (duration: 636.400499ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:50.819625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:50.182381Z","time spent":"637.229968ms","remote":"127.0.0.1:45990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:45:51.611419Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397419127317425890,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:45:52.11213Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397419127317425890,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:45:52.559325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.709208551s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:45:52.559428Z","caller":"traceutil/trace.go:171","msg":"trace[2084823571] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"1.736650996s","start":"2026-08-24T15:45:50.822756Z","end":"2026-08-24T15:45:52.559407Z","steps":["trace[2084823571] 'process raft request' (duration: 27.285621ms)","trace[2084823571] 'compare' (duration: 1.70876383s)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:45:52.559488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:50.822741Z","time spent":"1.736716657s","remote":"127.0.0.1:34444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1628,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:45:52.57951Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.729620566s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:45:52.57982Z","caller":"traceutil/trace.go:171","msg":"trace[1081468489] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:977; }","duration":"1.468936767s","start":"2026-08-24T15:45:51.110866Z","end":"2026-08-24T15:45:52.579803Z","steps":["trace[1081468489] 'read index received' (duration: 1.468798364s)","trace[1081468489] 'applied index is now lower than readState.Index' (duration: 137.743µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:45:52.579836Z","caller":"traceutil/trace.go:171","msg":"trace[2062839914] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"1.75614662s","start":"2026-08-24T15:45:50.823676Z","end":"2026-08-24T15:45:52.579823Z","steps":["trace[2062839914] 'process raft request' (duration: 1.755986226s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:52.579964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:50.823655Z","time spent":"1.756251492s","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:45:52.58Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.469097601s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:45:52.580064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.41049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:45:52.580071Z","caller":"traceutil/trace.go:171","msg":"trace[1668802358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"1.469225343s","start":"2026-08-24T15:45:51.110828Z","end":"2026-08-24T15:45:52.580053Z","steps":["trace[1668802358] 'agreement among raft nodes before linearized reading' (duration: 1.46906488s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:52.580085Z","caller":"traceutil/trace.go:171","msg":"trace[1383123804] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:945; }","duration":"191.431261ms","start":"2026-08-24T15:45:52.388646Z","end":"2026-08-24T15:45:52.580078Z","steps":["trace[1383123804] 'agreement among raft nodes before linearized reading' (duration: 191.40152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:52.580112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:51.110813Z","time spent":"1.469291015s","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:45:52.580194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.152987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:45:52.58021Z","caller":"traceutil/trace.go:171","msg":"trace[1303858926] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"1.754468071s","start":"2026-08-24T15:45:50.825731Z","end":"2026-08-24T15:45:52.580199Z","steps":["trace[1303858926] 'process raft request' (duration: 1.754036812s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:52.580031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.422239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:45:52.580249Z","caller":"traceutil/trace.go:171","msg":"trace[1018011875] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:945; }","duration":"310.259649ms","start":"2026-08-24T15:45:52.269973Z","end":"2026-08-24T15:45:52.580233Z","steps":["trace[1018011875] 'agreement among raft nodes before linearized reading' (duration: 310.154987ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:52.580264Z","caller":"traceutil/trace.go:171","msg":"trace[62497305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"564.694306ms","start":"2026-08-24T15:45:52.015563Z","end":"2026-08-24T15:45:52.580257Z","steps":["trace[62497305] 'agreement among raft nodes before linearized reading' (duration: 564.4425ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:52.580288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:52.015544Z","time spent":"564.735727ms","remote":"127.0.0.1:45802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:45:52.580277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:50.825714Z","time spent":"1.754529192s","remote":"127.0.0.1:34680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5192,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:45:52.580299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:52.269953Z","time spent":"310.32696ms","remote":"127.0.0.1:34554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-08-24T15:45:53.211169Z","caller":"traceutil/trace.go:171","msg":"trace[2015608858] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"386.765371ms","start":"2026-08-24T15:45:52.824385Z","end":"2026-08-24T15:45:53.21115Z","steps":["trace[2015608858] 'read index received' (duration: 386.661629ms)","trace[2015608858] 'applied index is now lower than readState.Index' (duration: 103.062µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:45:53.211239Z","caller":"traceutil/trace.go:171","msg":"trace[703857233] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"425.725049ms","start":"2026-08-24T15:45:52.785473Z","end":"2026-08-24T15:45:53.211198Z","steps":["trace[703857233] 'process raft request' (duration: 425.576156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:53.211404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:52.785461Z","time spent":"425.841221ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:45:53.211422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.012217ms","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-08-24T15:45:53.211495Z","caller":"traceutil/trace.go:171","msg":"trace[2066885667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:947; }","duration":"387.124589ms","start":"2026-08-24T15:45:52.824355Z","end":"2026-08-24T15:45:53.211479Z","steps":["trace[2066885667] 'agreement among raft nodes before linearized reading' (duration: 386.887854ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:53.211551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:52.824339Z","time spent":"387.205001ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:45:53.711883Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397419127317425900,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-24T15:45:53.98297Z","caller":"traceutil/trace.go:171","msg":"trace[1011534774] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"1.15701237s","start":"2026-08-24T15:45:52.825941Z","end":"2026-08-24T15:45:53.982953Z","steps":["trace[1011534774] 'process raft request' (duration: 1.156894257s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:53.983193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:52.825912Z","time spent":"1.157225784s","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:45:54.000424Z","caller":"traceutil/trace.go:171","msg":"trace[166532276] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:982; }","duration":"789.18513ms","start":"2026-08-24T15:45:53.211219Z","end":"2026-08-24T15:45:54.000404Z","steps":["trace[166532276] 'read index received' (duration: 771.844515ms)","trace[166532276] 'applied index is now lower than readState.Index' (duration: 17.339745ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:45:54.000452Z","caller":"traceutil/trace.go:171","msg":"trace[1646084570] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"781.28252ms","start":"2026-08-24T15:45:53.219138Z","end":"2026-08-24T15:45:54.000421Z","steps":["trace[1646084570] 'process raft request' (duration: 781.147227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:54.000523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.546191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:45:54.000593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174792984s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-24T15:45:54.000587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:53.219111Z","time spent":"781.401023ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:45:54.000619Z","caller":"traceutil/trace.go:171","msg":"trace[1930899978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:950; }","duration":"1.174839865s","start":"2026-08-24T15:45:52.825772Z","end":"2026-08-24T15:45:54.000612Z","steps":["trace[1930899978] 'agreement among raft nodes before linearized reading' (duration: 1.174734093s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:54.000608Z","caller":"traceutil/trace.go:171","msg":"trace[2126366188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"984.676723ms","start":"2026-08-24T15:45:53.015908Z","end":"2026-08-24T15:45:54.000584Z","steps":["trace[2126366188] 'agreement among raft nodes before linearized reading' (duration: 984.53687ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:54.000602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.499514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-24T15:45:54.000646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:52.825767Z","time spent":"1.174872186s","remote":"127.0.0.1:34442","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-08-24T15:45:54.000669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:53.015891Z","time spent":"984.761205ms","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:45:54.000674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175094291s","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-08-24T15:45:54.000699Z","caller":"traceutil/trace.go:171","msg":"trace[859684590] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:950; }","duration":"849.635397ms","start":"2026-08-24T15:45:53.151036Z","end":"2026-08-24T15:45:54.000671Z","steps":["trace[859684590] 'agreement among raft nodes before linearized reading' (duration: 849.466333ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:54.000743Z","caller":"traceutil/trace.go:171","msg":"trace[1087468405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:950; }","duration":"1.175195904s","start":"2026-08-24T15:45:52.825529Z","end":"2026-08-24T15:45:54.000725Z","steps":["trace[1087468405] 'agreement among raft nodes before linearized reading' (duration: 1.17501738s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:54.000753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175143132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-24T15:45:54.00075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.960953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.81\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-08-24T15:45:54.000779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:52.825514Z","time spent":"1.175257035s","remote":"127.0.0.1:34442","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-08-24T15:45:54.000775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:53.151018Z","time spent":"849.736929ms","remote":"127.0.0.1:34444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-08-24T15:45:54.000799Z","caller":"traceutil/trace.go:171","msg":"trace[1039399825] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:950; }","duration":"1.175201424s","start":"2026-08-24T15:45:52.825584Z","end":"2026-08-24T15:45:54.000786Z","steps":["trace[1039399825] 'agreement among raft nodes before linearized reading' (duration: 1.175087142s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:45:54.000817Z","caller":"traceutil/trace.go:171","msg":"trace[1546129922] range","detail":"{range_begin:/registry/masterleases/199.19.213.81; range_end:; response_count:1; response_revision:950; }","duration":"573.068016ms","start":"2026-08-24T15:45:53.427734Z","end":"2026-08-24T15:45:54.000802Z","steps":["trace[1546129922] 'agreement among raft nodes before linearized reading' (duration: 572.872361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:45:54.000835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:52.825576Z","time spent":"1.175250455s","remote":"127.0.0.1:34442","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-08-24T15:45:54.000863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:45:53.427715Z","time spent":"573.133387ms","remote":"127.0.0.1:45828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.81\" "} {"level":"info","ts":"2026-08-24T15:45:54.00118Z","caller":"traceutil/trace.go:171","msg":"trace[1971954665] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"245.731938ms","start":"2026-08-24T15:45:53.755431Z","end":"2026-08-24T15:45:54.001163Z","steps":["trace[1971954665] 'process raft request' (duration: 244.94341ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:46:00.266454Z","caller":"traceutil/trace.go:171","msg":"trace[791930945] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"176.90122ms","start":"2026-08-24T15:46:00.089524Z","end":"2026-08-24T15:46:00.266425Z","steps":["trace[791930945] 'process raft request' (duration: 176.750097ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:46:00.268769Z","caller":"traceutil/trace.go:171","msg":"trace[2010615088] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"176.586373ms","start":"2026-08-24T15:46:00.092165Z","end":"2026-08-24T15:46:00.268751Z","steps":["trace[2010615088] 'process raft request' (duration: 176.555413ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:46:00.268835Z","caller":"traceutil/trace.go:171","msg":"trace[609287666] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"178.575569ms","start":"2026-08-24T15:46:00.090226Z","end":"2026-08-24T15:46:00.268801Z","steps":["trace[609287666] 'process raft request' (duration: 178.392124ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:46:23.560486Z","caller":"traceutil/trace.go:171","msg":"trace[686330039] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"119.715087ms","start":"2026-08-24T15:46:23.440749Z","end":"2026-08-24T15:46:23.560464Z","steps":["trace[686330039] 'process raft request' (duration: 106.777712ms)","trace[686330039] 'compare' (duration: 12.844313ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:46:24.494472Z","caller":"traceutil/trace.go:171","msg":"trace[312176049] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1098; }","duration":"316.403279ms","start":"2026-08-24T15:46:24.178041Z","end":"2026-08-24T15:46:24.494445Z","steps":["trace[312176049] 'read index received' (duration: 316.143433ms)","trace[312176049] 'applied index is now lower than readState.Index' (duration: 258.605µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:46:24.494515Z","caller":"traceutil/trace.go:171","msg":"trace[1946474739] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"355.213772ms","start":"2026-08-24T15:46:24.139278Z","end":"2026-08-24T15:46:24.494492Z","steps":["trace[1946474739] 'process raft request' (duration: 354.891314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:24.494715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:24.139252Z","time spent":"355.369105ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:46:24.49486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.758916ms","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-08-24T15:46:24.495099Z","caller":"traceutil/trace.go:171","msg":"trace[1383782746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1057; }","duration":"316.961412ms","start":"2026-08-24T15:46:24.178004Z","end":"2026-08-24T15:46:24.494965Z","steps":["trace[1383782746] 'agreement among raft nodes before linearized reading' (duration: 316.574442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:24.495238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:24.17799Z","time spent":"317.227608ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-24T15:46:24.61814Z","caller":"traceutil/trace.go:171","msg":"trace[1980951390] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"116.32687ms","start":"2026-08-24T15:46:24.501777Z","end":"2026-08-24T15:46:24.618103Z","steps":["trace[1980951390] 'process raft request' (duration: 75.242284ms)","trace[1980951390] 'compare' (duration: 40.84493ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:46:24.61858Z","caller":"traceutil/trace.go:171","msg":"trace[834422730] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"116.689209ms","start":"2026-08-24T15:46:24.50186Z","end":"2026-08-24T15:46:24.618549Z","steps":["trace[834422730] 'process raft request' (duration: 116.177466ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:46:24.619088Z","caller":"traceutil/trace.go:171","msg":"trace[1719319405] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"116.973645ms","start":"2026-08-24T15:46:24.502098Z","end":"2026-08-24T15:46:24.619072Z","steps":["trace[1719319405] 'process raft request' (duration: 116.250179ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:26.335376Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397419127317426199,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:46:26.564904Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.097407912s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:46:26.566126Z","caller":"traceutil/trace.go:171","msg":"trace[1192226687] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"1.098692931s","start":"2026-08-24T15:46:25.46737Z","end":"2026-08-24T15:46:26.566063Z","steps":["trace[1192226687] 'process raft request' (duration: 1.097888743s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:26.56632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:25.467337Z","time spent":"1.098861875s","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:46:26.566525Z","caller":"traceutil/trace.go:171","msg":"trace[1400316323] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1104; }","duration":"731.876604ms","start":"2026-08-24T15:46:25.834631Z","end":"2026-08-24T15:46:26.566508Z","steps":["trace[1400316323] 'read index received' (duration: 730.690888ms)","trace[1400316323] 'applied index is now lower than readState.Index' (duration: 1.184436ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:46:26.566714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.067228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:46:26.566787Z","caller":"traceutil/trace.go:171","msg":"trace[341753914] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1063; }","duration":"732.189431ms","start":"2026-08-24T15:46:25.834581Z","end":"2026-08-24T15:46:26.56677Z","steps":["trace[341753914] 'agreement among raft nodes before linearized reading' (duration: 732.053928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:26.566837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:25.834555Z","time spent":"732.271682ms","remote":"127.0.0.1:34680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:46:27.208102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.267599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:46:27.208178Z","caller":"traceutil/trace.go:171","msg":"trace[1368235642] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1105; }","duration":"641.571457ms","start":"2026-08-24T15:46:26.566596Z","end":"2026-08-24T15:46:27.208167Z","steps":["trace[1368235642] 'read index received' (duration: 11.221206ms)","trace[1368235642] 'applied index is now lower than readState.Index' (duration: 630.349461ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:46:27.208516Z","caller":"traceutil/trace.go:171","msg":"trace[2117129828] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"707.461377ms","start":"2026-08-24T15:46:26.501044Z","end":"2026-08-24T15:46:27.208505Z","steps":["trace[2117129828] 'process raft request' (duration: 76.736328ms)","trace[2117129828] 'compare' (duration: 629.963522ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:46:27.20857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:26.501021Z","time spent":"707.523618ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:46:27.208647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.539092ms","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-08-24T15:46:27.208696Z","caller":"traceutil/trace.go:171","msg":"trace[26467448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1064; }","duration":"586.638754ms","start":"2026-08-24T15:46:26.622045Z","end":"2026-08-24T15:46:27.208684Z","steps":["trace[26467448] 'agreement among raft nodes before linearized reading' (duration: 586.495271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:27.208697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.457324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:46:27.208718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.716334ms","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-08-24T15:46:27.208739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:26.62202Z","time spent":"586.712057ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:46:27.208722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.842137ms","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-08-24T15:46:27.208757Z","caller":"traceutil/trace.go:171","msg":"trace[468636134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1064; }","duration":"192.578577ms","start":"2026-08-24T15:46:27.016162Z","end":"2026-08-24T15:46:27.20874Z","steps":["trace[468636134] 'agreement among raft nodes before linearized reading' (duration: 192.452504ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:46:27.208774Z","caller":"traceutil/trace.go:171","msg":"trace[596656857] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1064; }","duration":"585.783706ms","start":"2026-08-24T15:46:26.62296Z","end":"2026-08-24T15:46:27.208744Z","steps":["trace[596656857] 'agreement among raft nodes before linearized reading' (duration: 585.594872ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:27.208803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:26.622952Z","time spent":"585.842697ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-24T15:46:27.208804Z","caller":"traceutil/trace.go:171","msg":"trace[1152031099] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1064; }","duration":"585.994851ms","start":"2026-08-24T15:46:26.622788Z","end":"2026-08-24T15:46:27.208782Z","steps":["trace[1152031099] 'agreement among raft nodes before linearized reading' (duration: 585.763735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:27.208862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:26.622764Z","time spent":"586.088812ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T15:46:27.209055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.992747ms","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-08-24T15:46:27.209105Z","caller":"traceutil/trace.go:171","msg":"trace[405864659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1064; }","duration":"696.068838ms","start":"2026-08-24T15:46:26.513009Z","end":"2026-08-24T15:46:27.209078Z","steps":["trace[405864659] 'agreement among raft nodes before linearized reading' (duration: 695.882314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:27.209128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:26.512991Z","time spent":"696.131329ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-24T15:46:27.837835Z","caller":"traceutil/trace.go:171","msg":"trace[702294277] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"625.350297ms","start":"2026-08-24T15:46:27.212465Z","end":"2026-08-24T15:46:27.837815Z","steps":["trace[702294277] 'process raft request' (duration: 625.227035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:27.837993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:27.212452Z","time spent":"625.441829ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:46:27.856132Z","caller":"traceutil/trace.go:171","msg":"trace[418311383] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"643.172563ms","start":"2026-08-24T15:46:27.212946Z","end":"2026-08-24T15:46:27.856119Z","steps":["trace[418311383] 'process raft request' (duration: 643.144242ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:46:27.856196Z","caller":"traceutil/trace.go:171","msg":"trace[706618200] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"643.276755ms","start":"2026-08-24T15:46:27.21289Z","end":"2026-08-24T15:46:27.856166Z","steps":["trace[706618200] 'process raft request' (duration: 643.103601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:27.856249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:27.212939Z","time spent":"643.235374ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:46:27.856256Z","caller":"traceutil/trace.go:171","msg":"trace[1892263909] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"643.314796ms","start":"2026-08-24T15:46:27.212908Z","end":"2026-08-24T15:46:27.856223Z","steps":["trace[1892263909] 'process raft request' (duration: 643.158192ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:27.856363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:27.212878Z","time spent":"643.396138ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:46:27.856412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:27.212889Z","time spent":"643.442328ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:46:29.786009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.809135ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-24T15:46:29.786218Z","caller":"traceutil/trace.go:171","msg":"trace[1166605097] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"768.713993ms","start":"2026-08-24T15:46:29.017486Z","end":"2026-08-24T15:46:29.7862Z","steps":["trace[1166605097] 'read index received' (duration: 265.821227ms)","trace[1166605097] 'applied index is now lower than readState.Index' (duration: 502.891656ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:46:29.786316Z","caller":"traceutil/trace.go:171","msg":"trace[368311002] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"572.237857ms","start":"2026-08-24T15:46:29.21404Z","end":"2026-08-24T15:46:29.786277Z","steps":["trace[368311002] 'process raft request' (duration: 572.069603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:29.786458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:29.214019Z","time spent":"572.364229ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:46:29.786603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.846926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:46:29.786666Z","caller":"traceutil/trace.go:171","msg":"trace[1620571269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1070; }","duration":"769.207764ms","start":"2026-08-24T15:46:29.017446Z","end":"2026-08-24T15:46:29.786654Z","steps":["trace[1620571269] 'agreement among raft nodes before linearized reading' (duration: 768.846995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:29.786735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:29.017428Z","time spent":"769.268316ms","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:46:29.78686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.712607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:46:29.786942Z","caller":"traceutil/trace.go:171","msg":"trace[1925501844] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1070; }","duration":"672.84796ms","start":"2026-08-24T15:46:29.11407Z","end":"2026-08-24T15:46:29.786918Z","steps":["trace[1925501844] 'agreement among raft nodes before linearized reading' (duration: 672.695167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:29.787231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:29.11405Z","time spent":"673.163407ms","remote":"127.0.0.1:51250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:46:29.786964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.196311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:46:29.787319Z","caller":"traceutil/trace.go:171","msg":"trace[980610111] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1070; }","duration":"419.59525ms","start":"2026-08-24T15:46:29.367704Z","end":"2026-08-24T15:46:29.787299Z","steps":["trace[980610111] 'agreement among raft nodes before linearized reading' (duration: 419.1312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:29.787388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:29.367688Z","time spent":"419.678752ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":114,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-08-24T15:46:30.089872Z","caller":"traceutil/trace.go:171","msg":"trace[1259308059] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1113; }","duration":"228.991958ms","start":"2026-08-24T15:46:29.860856Z","end":"2026-08-24T15:46:30.089848Z","steps":["trace[1259308059] 'read index received' (duration: 228.774633ms)","trace[1259308059] 'applied index is now lower than readState.Index' (duration: 216.555µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:46:30.089985Z","caller":"traceutil/trace.go:171","msg":"trace[484635097] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"243.389485ms","start":"2026-08-24T15:46:29.846586Z","end":"2026-08-24T15:46:30.089975Z","steps":["trace[484635097] 'process raft request' (duration: 243.108468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:30.090223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.329615ms","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-08-24T15:46:30.090255Z","caller":"traceutil/trace.go:171","msg":"trace[1127038219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1071; }","duration":"229.442238ms","start":"2026-08-24T15:46:29.8608Z","end":"2026-08-24T15:46:30.090242Z","steps":["trace[1127038219] 'agreement among raft nodes before linearized reading' (duration: 229.304164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:30.090379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.491878ms","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-08-24T15:46:30.090396Z","caller":"traceutil/trace.go:171","msg":"trace[992946945] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1071; }","duration":"229.5336ms","start":"2026-08-24T15:46:29.860857Z","end":"2026-08-24T15:46:30.090391Z","steps":["trace[992946945] 'agreement among raft nodes before linearized reading' (duration: 229.476909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:30.090828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.795946ms","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-08-24T15:46:30.090939Z","caller":"traceutil/trace.go:171","msg":"trace[1994520898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1071; }","duration":"229.98748ms","start":"2026-08-24T15:46:29.860928Z","end":"2026-08-24T15:46:30.090916Z","steps":["trace[1994520898] 'agreement among raft nodes before linearized reading' (duration: 229.715324ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:30.68172Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397419127317426222,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:46:30.735871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.959009ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:46:30.736033Z","caller":"traceutil/trace.go:171","msg":"trace[1413859151] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1071; }","duration":"291.134303ms","start":"2026-08-24T15:46:30.444876Z","end":"2026-08-24T15:46:30.73601Z","steps":["trace[1413859151] 'range keys from in-memory index tree' (duration: 290.942898ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:46:30.736846Z","caller":"traceutil/trace.go:171","msg":"trace[119336645] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1114; }","duration":"556.193192ms","start":"2026-08-24T15:46:30.180581Z","end":"2026-08-24T15:46:30.736774Z","steps":["trace[119336645] 'read index received' (duration: 553.545871ms)","trace[119336645] 'applied index is now lower than readState.Index' (duration: 2.645821ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:46:30.73694Z","caller":"traceutil/trace.go:171","msg":"trace[1116782160] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"642.075458ms","start":"2026-08-24T15:46:30.09484Z","end":"2026-08-24T15:46:30.736915Z","steps":["trace[1116782160] 'process raft request' (duration: 639.267484ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:46:30.737035Z","caller":"traceutil/trace.go:171","msg":"trace[1758311858] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"640.480342ms","start":"2026-08-24T15:46:30.096414Z","end":"2026-08-24T15:46:30.736895Z","steps":["trace[1758311858] 'process raft request' (duration: 640.308568ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:30.737147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:30.094815Z","time spent":"642.218591ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:46:30.736984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.400947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:46:30.737275Z","caller":"traceutil/trace.go:171","msg":"trace[345185854] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1074; }","duration":"556.739364ms","start":"2026-08-24T15:46:30.180513Z","end":"2026-08-24T15:46:30.737253Z","steps":["trace[345185854] 'agreement among raft nodes before linearized reading' (duration: 556.426847ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:30.737356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:30.180491Z","time spent":"556.834997ms","remote":"127.0.0.1:45968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:46:30.737516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:30.096404Z","time spent":"640.779509ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:46:30.737612Z","caller":"traceutil/trace.go:171","msg":"trace[807458949] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"641.093026ms","start":"2026-08-24T15:46:30.095742Z","end":"2026-08-24T15:46:30.736835Z","steps":["trace[807458949] 'process raft request' (duration: 640.898522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:30.737694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:30.095729Z","time spent":"641.927254ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:46:31.095393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.252873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:46:31.095501Z","caller":"traceutil/trace.go:171","msg":"trace[434935542] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1075; }","duration":"229.430457ms","start":"2026-08-24T15:46:30.866047Z","end":"2026-08-24T15:46:31.095478Z","steps":["trace[434935542] 'count revisions from in-memory index tree' (duration: 229.163021ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:46:40.909999Z","caller":"traceutil/trace.go:171","msg":"trace[467363154] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"122.710546ms","start":"2026-08-24T15:46:40.787265Z","end":"2026-08-24T15:46:40.909976Z","steps":["trace[467363154] 'process raft request' (duration: 122.578843ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:46:40.977219Z","caller":"traceutil/trace.go:171","msg":"trace[1382109447] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1149; }","duration":"126.958142ms","start":"2026-08-24T15:46:40.850243Z","end":"2026-08-24T15:46:40.977202Z","steps":["trace[1382109447] 'read index received' (duration: 59.624708ms)","trace[1382109447] 'applied index is now lower than readState.Index' (duration: 67.332484ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:46:40.977269Z","caller":"traceutil/trace.go:171","msg":"trace[1047427344] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"189.052137ms","start":"2026-08-24T15:46:40.788196Z","end":"2026-08-24T15:46:40.977248Z","steps":["trace[1047427344] 'process raft request' (duration: 188.879034ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:46:40.977311Z","caller":"traceutil/trace.go:171","msg":"trace[711238748] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"188.705199ms","start":"2026-08-24T15:46:40.788576Z","end":"2026-08-24T15:46:40.977282Z","steps":["trace[711238748] 'process raft request' (duration: 188.597467ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:40.977401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.112975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-24T15:46:40.977434Z","caller":"traceutil/trace.go:171","msg":"trace[1458699153] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1107; }","duration":"127.249769ms","start":"2026-08-24T15:46:40.850176Z","end":"2026-08-24T15:46:40.977426Z","steps":["trace[1458699153] 'agreement among raft nodes before linearized reading' (duration: 127.096956ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:46:50.219933Z","caller":"traceutil/trace.go:171","msg":"trace[867047322] linearizableReadLoop","detail":"{readStateIndex:1235; appliedIndex:1234; }","duration":"204.303765ms","start":"2026-08-24T15:46:50.015612Z","end":"2026-08-24T15:46:50.219915Z","steps":["trace[867047322] 'read index received' (duration: 204.13375ms)","trace[867047322] 'applied index is now lower than readState.Index' (duration: 169.165µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:46:50.220298Z","caller":"traceutil/trace.go:171","msg":"trace[1464084426] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"344.851626ms","start":"2026-08-24T15:46:49.875433Z","end":"2026-08-24T15:46:50.220284Z","steps":["trace[1464084426] 'process raft request' (duration: 344.367004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:50.220405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:49.875408Z","time spent":"344.927298ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:46:50.220579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.97426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:46:50.220609Z","caller":"traceutil/trace.go:171","msg":"trace[459422703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1186; }","duration":"205.058272ms","start":"2026-08-24T15:46:50.015542Z","end":"2026-08-24T15:46:50.2206Z","steps":["trace[459422703] 'agreement among raft nodes before linearized reading' (duration: 205.012721ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:46:51.164955Z","caller":"traceutil/trace.go:171","msg":"trace[595052475] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"121.347014ms","start":"2026-08-24T15:46:51.043591Z","end":"2026-08-24T15:46:51.164938Z","steps":["trace[595052475] 'process raft request' (duration: 121.134739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:52.52352Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397419127317426499,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:46:53.024212Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397419127317426499,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:46:53.350865Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.824850365s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:46:53.351288Z","caller":"traceutil/trace.go:171","msg":"trace[134099980] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"1.825346756s","start":"2026-08-24T15:46:51.525912Z","end":"2026-08-24T15:46:53.351259Z","steps":["trace[134099980] 'process raft request' (duration: 1.825173082s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:53.35145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:51.525879Z","time spent":"1.825492529s","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:46:54.023387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00053569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-24T15:46:54.023542Z","caller":"traceutil/trace.go:171","msg":"trace[165264491] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000735015s","start":"2026-08-24T15:46:52.022786Z","end":"2026-08-24T15:46:54.023521Z","steps":["trace[165264491] 'agreement among raft nodes before linearized reading' (duration: 2.000551841s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:54.023595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:52.022773Z","time spent":"2.000809817s","remote":"127.0.0.1:45794","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-08-24T15:46:54.642974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.385896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:46:54.643062Z","caller":"traceutil/trace.go:171","msg":"trace[1697780748] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1240; }","duration":"2.620224624s","start":"2026-08-24T15:46:52.022813Z","end":"2026-08-24T15:46:54.643038Z","steps":["trace[1697780748] 'read index received' (duration: 1.328307083s)","trace[1697780748] 'applied index is now lower than readState.Index' (duration: 1.291916751s)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:46:54.643893Z","caller":"traceutil/trace.go:171","msg":"trace[1461702428] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"2.416442251s","start":"2026-08-24T15:46:52.227381Z","end":"2026-08-24T15:46:54.643823Z","steps":["trace[1461702428] 'process raft request' (duration: 2.134144304s)","trace[1461702428] 'compare' (duration: 281.246813ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:46:54.644093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:52.227358Z","time spent":"2.416638466s","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:46:54.645645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.41243085s","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-08-24T15:46:54.645666Z","caller":"traceutil/trace.go:171","msg":"trace[1905051623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1193; }","duration":"2.41248263s","start":"2026-08-24T15:46:52.233178Z","end":"2026-08-24T15:46:54.645661Z","steps":["trace[1905051623] 'agreement among raft nodes before linearized reading' (duration: 2.412386118s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:54.645687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:52.233165Z","time spent":"2.412516331s","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:46:54.64577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.438567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:46:54.64585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.215955321s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.81\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-24T15:46:54.645881Z","caller":"traceutil/trace.go:171","msg":"trace[1071207962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1193; }","duration":"619.566661ms","start":"2026-08-24T15:46:54.026295Z","end":"2026-08-24T15:46:54.645861Z","steps":["trace[1071207962] 'agreement among raft nodes before linearized reading' (duration: 619.421558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:54.645902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.64653617s","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-08-24T15:46:54.6459Z","caller":"traceutil/trace.go:171","msg":"trace[1003704410] range","detail":"{range_begin:/registry/masterleases/199.19.213.81; range_end:; response_count:1; response_revision:1193; }","duration":"1.216054122s","start":"2026-08-24T15:46:53.429834Z","end":"2026-08-24T15:46:54.645888Z","steps":["trace[1003704410] 'agreement among raft nodes before linearized reading' (duration: 1.215777936s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:54.646398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:53.429821Z","time spent":"1.216566726s","remote":"127.0.0.1:45828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.81\" "} {"level":"info","ts":"2026-08-24T15:46:54.645919Z","caller":"traceutil/trace.go:171","msg":"trace[2002150104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1193; }","duration":"1.646616001s","start":"2026-08-24T15:46:52.999298Z","end":"2026-08-24T15:46:54.645914Z","steps":["trace[2002150104] 'agreement among raft nodes before linearized reading' (duration: 1.64657749s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:54.646504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:52.999266Z","time spent":"1.647227306s","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T15:46:54.645927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:54.026281Z","time spent":"619.637292ms","remote":"127.0.0.1:45802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:46:54.64603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.44285699s","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-08-24T15:46:54.646666Z","caller":"traceutil/trace.go:171","msg":"trace[751017938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1193; }","duration":"1.443510336s","start":"2026-08-24T15:46:53.203148Z","end":"2026-08-24T15:46:54.646658Z","steps":["trace[751017938] 'agreement among raft nodes before linearized reading' (duration: 1.44285172s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:54.646687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:53.203137Z","time spent":"1.443545236s","remote":"127.0.0.1:34442","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-08-24T15:46:54.645967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446740308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-24T15:46:54.646762Z","caller":"traceutil/trace.go:171","msg":"trace[2072031286] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1193; }","duration":"1.447604579s","start":"2026-08-24T15:46:53.199151Z","end":"2026-08-24T15:46:54.646755Z","steps":["trace[2072031286] 'agreement among raft nodes before linearized reading' (duration: 1.446523174s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:54.646786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:53.199138Z","time spent":"1.447642009s","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-24T15:46:54.645864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.477194752s","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-08-24T15:46:54.646851Z","caller":"traceutil/trace.go:171","msg":"trace[1402217675] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1193; }","duration":"1.478240877s","start":"2026-08-24T15:46:53.168598Z","end":"2026-08-24T15:46:54.646839Z","steps":["trace[1402217675] 'agreement among raft nodes before linearized reading' (duration: 1.477191012s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:54.646883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:53.168585Z","time spent":"1.478290597s","remote":"127.0.0.1:34442","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-08-24T15:46:54.646295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.869665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:46:54.646908Z","caller":"traceutil/trace.go:171","msg":"trace[2099546462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1193; }","duration":"812.52858ms","start":"2026-08-24T15:46:53.834374Z","end":"2026-08-24T15:46:54.646903Z","steps":["trace[2099546462] 'agreement among raft nodes before linearized reading' (duration: 811.603048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:54.646928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:46:53.834353Z","time spent":"812.570811ms","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T15:47:02.817845Z","caller":"traceutil/trace.go:171","msg":"trace[354943113] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1271; }","duration":"109.129787ms","start":"2026-08-24T15:47:02.708692Z","end":"2026-08-24T15:47:02.817822Z","steps":["trace[354943113] 'read index received' (duration: 45.087703ms)","trace[354943113] 'applied index is now lower than readState.Index' (duration: 64.041174ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:47:02.818093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.370332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-24T15:47:02.818146Z","caller":"traceutil/trace.go:171","msg":"trace[1129604884] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1224; }","duration":"109.488205ms","start":"2026-08-24T15:47:02.708647Z","end":"2026-08-24T15:47:02.818135Z","steps":["trace[1129604884] 'agreement among raft nodes before linearized reading' (duration: 109.27162ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:02.818218Z","caller":"traceutil/trace.go:171","msg":"trace[869023731] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"117.548488ms","start":"2026-08-24T15:47:02.700639Z","end":"2026-08-24T15:47:02.818188Z","steps":["trace[869023731] 'process raft request' (duration: 116.870352ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:02.818409Z","caller":"traceutil/trace.go:171","msg":"trace[1002339666] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"117.760493ms","start":"2026-08-24T15:47:02.700639Z","end":"2026-08-24T15:47:02.818399Z","steps":["trace[1002339666] 'process raft request' (duration: 116.983535ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:02.818428Z","caller":"traceutil/trace.go:171","msg":"trace[571041616] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"117.697932ms","start":"2026-08-24T15:47:02.700688Z","end":"2026-08-24T15:47:02.818386Z","steps":["trace[571041616] 'process raft request' (duration: 117.029227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:04.069088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:03.430132Z","time spent":"638.952751ms","remote":"127.0.0.1:45828","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-24T15:47:04.991537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.000117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:47:04.99167Z","caller":"traceutil/trace.go:171","msg":"trace[1408095409] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"1.421394769s","start":"2026-08-24T15:47:03.570253Z","end":"2026-08-24T15:47:04.991648Z","steps":["trace[1408095409] 'process raft request' (duration: 1.240223827s)","trace[1408095409] 'compare' (duration: 180.781402ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:47:04.991728Z","caller":"traceutil/trace.go:171","msg":"trace[463763519] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"289.649074ms","start":"2026-08-24T15:47:04.702066Z","end":"2026-08-24T15:47:04.991715Z","steps":["trace[463763519] 'process raft request' (duration: 289.615723ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:04.991782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:03.570231Z","time spent":"1.421491081s","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:47:04.991857Z","caller":"traceutil/trace.go:171","msg":"trace[743493735] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"921.700968ms","start":"2026-08-24T15:47:04.070151Z","end":"2026-08-24T15:47:04.991852Z","steps":["trace[743493735] 'process raft request' (duration: 921.463543ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:04.991885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:04.07013Z","time spent":"921.738159ms","remote":"127.0.0.1:45828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:47:04.991971Z","caller":"traceutil/trace.go:171","msg":"trace[52105228] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1276; }","duration":"1.154870971s","start":"2026-08-24T15:47:03.837094Z","end":"2026-08-24T15:47:04.991965Z","steps":["trace[52105228] 'read index received' (duration: 231.799861ms)","trace[52105228] 'applied index is now lower than readState.Index' (duration: 923.07046ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:47:04.99206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154964783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:47:04.992081Z","caller":"traceutil/trace.go:171","msg":"trace[1019516511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"1.154988383s","start":"2026-08-24T15:47:03.837089Z","end":"2026-08-24T15:47:04.992077Z","steps":["trace[1019516511] 'agreement among raft nodes before linearized reading' (duration: 1.154946442s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:04.992097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:03.837051Z","time spent":"1.155040985s","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:47:04.992149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.876641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:47:04.992181Z","caller":"traceutil/trace.go:171","msg":"trace[190922791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"976.933841ms","start":"2026-08-24T15:47:04.015239Z","end":"2026-08-24T15:47:04.992173Z","steps":["trace[190922791] 'agreement among raft nodes before linearized reading' (duration: 976.88481ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:04.992208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:04.015225Z","time spent":"976.976602ms","remote":"127.0.0.1:45802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:47:04.992298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.400385ms","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-08-24T15:47:04.992319Z","caller":"traceutil/trace.go:171","msg":"trace[1116610714] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1228; }","duration":"169.436066ms","start":"2026-08-24T15:47:04.822878Z","end":"2026-08-24T15:47:04.992314Z","steps":["trace[1116610714] 'agreement among raft nodes before linearized reading' (duration: 169.359924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:04.992376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.526738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-24T15:47:04.992404Z","caller":"traceutil/trace.go:171","msg":"trace[1951480272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1228; }","duration":"169.553688ms","start":"2026-08-24T15:47:04.822843Z","end":"2026-08-24T15:47:04.992396Z","steps":["trace[1951480272] 'agreement among raft nodes before linearized reading' (duration: 169.460076ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:04.992487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.564438ms","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-08-24T15:47:04.992506Z","caller":"traceutil/trace.go:171","msg":"trace[1192971147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1228; }","duration":"169.59444ms","start":"2026-08-24T15:47:04.822906Z","end":"2026-08-24T15:47:04.992501Z","steps":["trace[1192971147] 'agreement among raft nodes before linearized reading' (duration: 169.550619ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:04.992567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.672017ms","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-08-24T15:47:04.992594Z","caller":"traceutil/trace.go:171","msg":"trace[509331146] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1228; }","duration":"216.723498ms","start":"2026-08-24T15:47:04.775864Z","end":"2026-08-24T15:47:04.992587Z","steps":["trace[509331146] 'agreement among raft nodes before linearized reading' (duration: 216.647046ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:05.15387Z","caller":"traceutil/trace.go:171","msg":"trace[958998851] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"154.522687ms","start":"2026-08-24T15:47:04.999323Z","end":"2026-08-24T15:47:05.153845Z","steps":["trace[958998851] 'process raft request' (duration: 106.227661ms)","trace[958998851] 'compare' (duration: 48.137372ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:47:05.154112Z","caller":"traceutil/trace.go:171","msg":"trace[737242269] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"153.572905ms","start":"2026-08-24T15:47:05.000519Z","end":"2026-08-24T15:47:05.154092Z","steps":["trace[737242269] 'process raft request' (duration: 153.447032ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:05.154166Z","caller":"traceutil/trace.go:171","msg":"trace[1773414497] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1281; }","duration":"154.023856ms","start":"2026-08-24T15:47:05.000123Z","end":"2026-08-24T15:47:05.154147Z","steps":["trace[1773414497] 'read index received' (duration: 24.416463ms)","trace[1773414497] 'applied index is now lower than readState.Index' (duration: 129.606443ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:47:05.154261Z","caller":"traceutil/trace.go:171","msg":"trace[405421910] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"153.708669ms","start":"2026-08-24T15:47:05.000545Z","end":"2026-08-24T15:47:05.154254Z","steps":["trace[405421910] 'process raft request' (duration: 153.504604ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:05.154351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.20622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-24T15:47:05.15438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.258655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:47:05.154402Z","caller":"traceutil/trace.go:171","msg":"trace[1371006769] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1233; }","duration":"154.280241ms","start":"2026-08-24T15:47:05.000105Z","end":"2026-08-24T15:47:05.154385Z","steps":["trace[1371006769] 'agreement among raft nodes before linearized reading' (duration: 154.158268ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:05.154443Z","caller":"traceutil/trace.go:171","msg":"trace[784885736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1233; }","duration":"137.362958ms","start":"2026-08-24T15:47:05.017065Z","end":"2026-08-24T15:47:05.154428Z","steps":["trace[784885736] 'agreement among raft nodes before linearized reading' (duration: 137.233695ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:05.156362Z","caller":"traceutil/trace.go:171","msg":"trace[1531412018] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"154.717342ms","start":"2026-08-24T15:47:04.999387Z","end":"2026-08-24T15:47:05.154105Z","steps":["trace[1531412018] 'process raft request' (duration: 154.414934ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:07.503798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8397419127317426609,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-24T15:47:07.554919Z","caller":"traceutil/trace.go:171","msg":"trace[1628631352] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1286; }","duration":"552.004609ms","start":"2026-08-24T15:47:07.002899Z","end":"2026-08-24T15:47:07.554903Z","steps":["trace[1628631352] 'read index received' (duration: 551.837144ms)","trace[1628631352] 'applied index is now lower than readState.Index' (duration: 166.945µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:47:07.555023Z","caller":"traceutil/trace.go:171","msg":"trace[1503952724] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"552.330626ms","start":"2026-08-24T15:47:07.002686Z","end":"2026-08-24T15:47:07.555017Z","steps":["trace[1503952724] 'process raft request' (duration: 552.115941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:07.555091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:07.002662Z","time spent":"552.377997ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:47:07.555369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.875641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-24T15:47:07.555258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.636637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:47:07.555435Z","caller":"traceutil/trace.go:171","msg":"trace[1473256644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1235; }","duration":"379.977873ms","start":"2026-08-24T15:47:07.175444Z","end":"2026-08-24T15:47:07.555422Z","steps":["trace[1473256644] 'agreement among raft nodes before linearized reading' (duration: 379.84353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:07.555433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.53646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:47:07.555498Z","caller":"traceutil/trace.go:171","msg":"trace[1069570987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1235; }","duration":"539.863463ms","start":"2026-08-24T15:47:07.015581Z","end":"2026-08-24T15:47:07.555445Z","steps":["trace[1069570987] 'agreement among raft nodes before linearized reading' (duration: 539.643168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:07.555487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.257196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-24T15:47:07.555531Z","caller":"traceutil/trace.go:171","msg":"trace[399322740] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1235; }","duration":"552.694204ms","start":"2026-08-24T15:47:07.002807Z","end":"2026-08-24T15:47:07.555501Z","steps":["trace[399322740] 'agreement among raft nodes before linearized reading' (duration: 552.50678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:07.555534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.018301ms","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-08-24T15:47:07.555548Z","caller":"traceutil/trace.go:171","msg":"trace[1376322312] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1235; }","duration":"397.381099ms","start":"2026-08-24T15:47:07.158152Z","end":"2026-08-24T15:47:07.555533Z","steps":["trace[1376322312] 'agreement among raft nodes before linearized reading' (duration: 397.176664ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:07.555524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:07.015565Z","time spent":"539.949744ms","remote":"127.0.0.1:45802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T15:47:07.555569Z","caller":"traceutil/trace.go:171","msg":"trace[920535899] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1235; }","duration":"527.067191ms","start":"2026-08-24T15:47:07.028491Z","end":"2026-08-24T15:47:07.555558Z","steps":["trace[920535899] 'agreement among raft nodes before linearized reading' (duration: 526.99273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:07.555584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:07.158129Z","time spent":"397.447631ms","remote":"127.0.0.1:34442","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":"warn","ts":"2026-08-24T15:47:07.555594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:07.028481Z","time spent":"527.107832ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-24T15:47:07.55557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:07.002785Z","time spent":"552.776846ms","remote":"127.0.0.1:33066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:47:07.555704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.822086ms","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-08-24T15:47:07.555767Z","caller":"traceutil/trace.go:171","msg":"trace[451128595] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1235; }","duration":"378.893398ms","start":"2026-08-24T15:47:07.176868Z","end":"2026-08-24T15:47:07.555761Z","steps":["trace[451128595] 'agreement among raft nodes before linearized reading' (duration: 378.733614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:07.555782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:07.176864Z","time spent":"378.913838ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T15:47:07.555467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:07.175421Z","time spent":"380.039394ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:47:07.556057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.195384ms","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-08-24T15:47:07.5561Z","caller":"traceutil/trace.go:171","msg":"trace[341862399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1235; }","duration":"379.254116ms","start":"2026-08-24T15:47:07.176835Z","end":"2026-08-24T15:47:07.556089Z","steps":["trace[341862399] 'agreement among raft nodes before linearized reading' (duration: 378.449908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:07.55612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:07.176827Z","time spent":"379.289227ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-24T15:47:07.871376Z","caller":"traceutil/trace.go:171","msg":"trace[197153257] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"308.840089ms","start":"2026-08-24T15:47:07.562511Z","end":"2026-08-24T15:47:07.871352Z","steps":["trace[197153257] 'process raft request' (duration: 308.670594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:07.871519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:07.562499Z","time spent":"308.944162ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:47:07.893269Z","caller":"traceutil/trace.go:171","msg":"trace[499035712] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"330.366327ms","start":"2026-08-24T15:47:07.562873Z","end":"2026-08-24T15:47:07.893239Z","steps":["trace[499035712] 'process raft request' (duration: 330.293076ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:07.893294Z","caller":"traceutil/trace.go:171","msg":"trace[52215493] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"330.732675ms","start":"2026-08-24T15:47:07.562506Z","end":"2026-08-24T15:47:07.893239Z","steps":["trace[52215493] 'process raft request' (duration: 330.440169ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:07.893444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:07.562837Z","time spent":"330.516121ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:47:07.893515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:07.562484Z","time spent":"330.90972ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:47:07.89358Z","caller":"traceutil/trace.go:171","msg":"trace[1652817013] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"330.190792ms","start":"2026-08-24T15:47:07.56336Z","end":"2026-08-24T15:47:07.89355Z","steps":["trace[1652817013] 'process raft request' (duration: 329.841324ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:07.893678Z","caller":"traceutil/trace.go:171","msg":"trace[194294483] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"330.982841ms","start":"2026-08-24T15:47:07.562685Z","end":"2026-08-24T15:47:07.893668Z","steps":["trace[194294483] 'process raft request' (duration: 330.436569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:07.893716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:07.562652Z","time spent":"331.042603ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:47:07.893729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:07.563348Z","time spent":"330.298875ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:47:09.739574Z","caller":"traceutil/trace.go:171","msg":"trace[1681873642] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"176.671949ms","start":"2026-08-24T15:47:09.562883Z","end":"2026-08-24T15:47:09.739555Z","steps":["trace[1681873642] 'process raft request' (duration: 176.551697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:10.42318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.284213ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-24T15:47:10.423295Z","caller":"traceutil/trace.go:171","msg":"trace[608516925] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1293; }","duration":"547.939145ms","start":"2026-08-24T15:47:09.875335Z","end":"2026-08-24T15:47:10.423274Z","steps":["trace[608516925] 'read index received' (duration: 52.159563ms)","trace[608516925] 'applied index is now lower than readState.Index' (duration: 495.777492ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:47:10.423747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.406306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-08-24T15:47:10.424218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.536038ms","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-08-24T15:47:10.424261Z","caller":"traceutil/trace.go:171","msg":"trace[1208496890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1241; }","duration":"525.604158ms","start":"2026-08-24T15:47:09.898645Z","end":"2026-08-24T15:47:10.424249Z","steps":["trace[1208496890] 'agreement among raft nodes before linearized reading' (duration: 525.482376ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:10.424298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:09.89862Z","time spent":"525.66766ms","remote":"127.0.0.1:34442","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-08-24T15:47:10.42442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.721078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-24T15:47:10.424447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.900406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-24T15:47:10.424522Z","caller":"traceutil/trace.go:171","msg":"trace[1694925232] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1241; }","duration":"524.862781ms","start":"2026-08-24T15:47:09.899637Z","end":"2026-08-24T15:47:10.4245Z","steps":["trace[1694925232] 'agreement among raft nodes before linearized reading' (duration: 524.473982ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:10.424543Z","caller":"traceutil/trace.go:171","msg":"trace[1275109388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1241; }","duration":"526.041638ms","start":"2026-08-24T15:47:09.898475Z","end":"2026-08-24T15:47:10.424517Z","steps":["trace[1275109388] 'agreement among raft nodes before linearized reading' (duration: 525.631119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:10.424575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:09.899625Z","time spent":"524.936573ms","remote":"127.0.0.1:34442","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-08-24T15:47:10.424606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:09.898459Z","time spent":"526.133431ms","remote":"127.0.0.1:34442","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-08-24T15:47:10.424693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.038478ms","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-08-24T15:47:10.42472Z","caller":"traceutil/trace.go:171","msg":"trace[642847785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1241; }","duration":"526.10246ms","start":"2026-08-24T15:47:09.89861Z","end":"2026-08-24T15:47:10.424712Z","steps":["trace[642847785] 'agreement among raft nodes before linearized reading' (duration: 526.031638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:10.424745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:09.89859Z","time spent":"526.150701ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:47:10.424834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.39397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:47:10.424853Z","caller":"traceutil/trace.go:171","msg":"trace[2643434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1241; }","duration":"405.410981ms","start":"2026-08-24T15:47:10.019435Z","end":"2026-08-24T15:47:10.424846Z","steps":["trace[2643434] 'agreement among raft nodes before linearized reading' (duration: 405.3804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:10.424869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:10.019372Z","time spent":"405.493103ms","remote":"127.0.0.1:45802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T15:47:10.425168Z","caller":"traceutil/trace.go:171","msg":"trace[1878222482] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1241; }","duration":"549.878179ms","start":"2026-08-24T15:47:09.87528Z","end":"2026-08-24T15:47:10.425158Z","steps":["trace[1878222482] 'agreement among raft nodes before linearized reading' (duration: 548.323104ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:10.425239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:09.875258Z","time spent":"549.968341ms","remote":"127.0.0.1:34442","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-08-24T15:47:14.958251Z","caller":"traceutil/trace.go:171","msg":"trace[950662429] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"190.983015ms","start":"2026-08-24T15:47:14.767245Z","end":"2026-08-24T15:47:14.958228Z","steps":["trace[950662429] 'process raft request' (duration: 190.834981ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:15.944464Z","caller":"traceutil/trace.go:171","msg":"trace[1187730142] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"133.004888ms","start":"2026-08-24T15:47:15.811414Z","end":"2026-08-24T15:47:15.944419Z","steps":["trace[1187730142] 'process raft request' (duration: 132.038366ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:18.20678Z","caller":"traceutil/trace.go:171","msg":"trace[245303110] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1348; }","duration":"240.887097ms","start":"2026-08-24T15:47:17.965874Z","end":"2026-08-24T15:47:18.206761Z","steps":["trace[245303110] 'read index received' (duration: 240.779854ms)","trace[245303110] 'applied index is now lower than readState.Index' (duration: 106.382µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:47:18.206853Z","caller":"traceutil/trace.go:171","msg":"trace[633174147] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"243.316472ms","start":"2026-08-24T15:47:17.963502Z","end":"2026-08-24T15:47:18.206819Z","steps":["trace[633174147] 'process raft request' (duration: 243.144938ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:18.206935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.049761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:47:18.206969Z","caller":"traceutil/trace.go:171","msg":"trace[1873560081] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1295; }","duration":"241.169213ms","start":"2026-08-24T15:47:17.965791Z","end":"2026-08-24T15:47:18.20696Z","steps":["trace[1873560081] 'agreement among raft nodes before linearized reading' (duration: 241.080341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:18.206983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.640971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:47:18.207092Z","caller":"traceutil/trace.go:171","msg":"trace[275959964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1295; }","duration":"169.793043ms","start":"2026-08-24T15:47:18.037281Z","end":"2026-08-24T15:47:18.207074Z","steps":["trace[275959964] 'agreement among raft nodes before linearized reading' (duration: 169.62642ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:20.935565Z","caller":"traceutil/trace.go:171","msg":"trace[443891215] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1373; }","duration":"383.393102ms","start":"2026-08-24T15:47:20.552125Z","end":"2026-08-24T15:47:20.935518Z","steps":["trace[443891215] 'read index received' (duration: 383.214978ms)","trace[443891215] 'applied index is now lower than readState.Index' (duration: 177.224µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:47:20.935614Z","caller":"traceutil/trace.go:171","msg":"trace[1145624001] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"383.591656ms","start":"2026-08-24T15:47:20.551991Z","end":"2026-08-24T15:47:20.935582Z","steps":["trace[1145624001] 'process raft request' (duration: 383.401532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:20.935745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:20.551977Z","time spent":"383.696738ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:47:20.935756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.606896ms","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-08-24T15:47:20.935797Z","caller":"traceutil/trace.go:171","msg":"trace[2145329145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1319; }","duration":"383.678167ms","start":"2026-08-24T15:47:20.552105Z","end":"2026-08-24T15:47:20.935783Z","steps":["trace[2145329145] 'agreement among raft nodes before linearized reading' (duration: 383.525354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:20.936112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:20.552099Z","time spent":"383.772409ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-24T15:47:22.700011Z","caller":"traceutil/trace.go:171","msg":"trace[514003585] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"162.113709ms","start":"2026-08-24T15:47:22.537877Z","end":"2026-08-24T15:47:22.69999Z","steps":["trace[514003585] 'process raft request' (duration: 161.946555ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:22.701693Z","caller":"traceutil/trace.go:171","msg":"trace[1012173873] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1377; }","duration":"150.179168ms","start":"2026-08-24T15:47:22.551492Z","end":"2026-08-24T15:47:22.701671Z","steps":["trace[1012173873] 'read index received' (duration: 148.349087ms)","trace[1012173873] 'applied index is now lower than readState.Index' (duration: 1.829231ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:47:22.701881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.366753ms","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-08-24T15:47:22.701918Z","caller":"traceutil/trace.go:171","msg":"trace[520741137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1324; }","duration":"150.441085ms","start":"2026-08-24T15:47:22.551463Z","end":"2026-08-24T15:47:22.701904Z","steps":["trace[520741137] 'agreement among raft nodes before linearized reading' (duration: 150.28731ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:22.701895Z","caller":"traceutil/trace.go:171","msg":"trace[221533551] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"162.279283ms","start":"2026-08-24T15:47:22.539578Z","end":"2026-08-24T15:47:22.701858Z","steps":["trace[221533551] 'process raft request' (duration: 161.970765ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:18.744444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.850016ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-08-24T15:48:25.240636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.601222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:48:25.240962Z","caller":"traceutil/trace.go:171","msg":"trace[882147100] transaction","detail":"{read_only:false; response_revision:1629; number_of_response:1; }","duration":"186.333615ms","start":"2026-08-24T15:48:25.0546Z","end":"2026-08-24T15:48:25.240933Z","steps":["trace[882147100] 'process raft request' (duration: 186.215442ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:48:25.240976Z","caller":"traceutil/trace.go:171","msg":"trace[1712522813] transaction","detail":"{read_only:false; response_revision:1628; number_of_response:1; }","duration":"186.529139ms","start":"2026-08-24T15:48:25.054404Z","end":"2026-08-24T15:48:25.240933Z","steps":["trace[1712522813] 'process raft request' (duration: 71.473937ms)","trace[1712522813] 'compare' (duration: 114.368007ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:48:25.464277Z","caller":"traceutil/trace.go:171","msg":"trace[1165986062] transaction","detail":"{read_only:false; response_revision:1632; number_of_response:1; }","duration":"156.4972ms","start":"2026-08-24T15:48:25.307754Z","end":"2026-08-24T15:48:25.464251Z","steps":["trace[1165986062] 'process raft request' (duration: 156.447389ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:48:25.464394Z","caller":"traceutil/trace.go:171","msg":"trace[1153069190] transaction","detail":"{read_only:false; response_revision:1631; number_of_response:1; }","duration":"160.565522ms","start":"2026-08-24T15:48:25.303761Z","end":"2026-08-24T15:48:25.464327Z","steps":["trace[1153069190] 'process raft request' (duration: 117.215401ms)","trace[1153069190] 'compare' (duration: 43.060935ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:48:27.479444Z","caller":"traceutil/trace.go:171","msg":"trace[1891462617] linearizableReadLoop","detail":"{readStateIndex:1709; appliedIndex:1708; }","duration":"109.464546ms","start":"2026-08-24T15:48:27.369926Z","end":"2026-08-24T15:48:27.47939Z","steps":["trace[1891462617] 'read index received' (duration: 27.877101ms)","trace[1891462617] 'applied index is now lower than readState.Index' (duration: 81.586345ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:48:27.479587Z","caller":"traceutil/trace.go:171","msg":"trace[761595051] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"145.528372ms","start":"2026-08-24T15:48:27.334036Z","end":"2026-08-24T15:48:27.479564Z","steps":["trace[761595051] 'process raft request' (duration: 63.765213ms)","trace[761595051] 'compare' (duration: 81.465672ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:48:27.479637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.682841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:48:27.479698Z","caller":"traceutil/trace.go:171","msg":"trace[415021199] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1641; }","duration":"109.763043ms","start":"2026-08-24T15:48:27.369918Z","end":"2026-08-24T15:48:27.479681Z","steps":["trace[415021199] 'agreement among raft nodes before linearized reading' (duration: 109.63357ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:48:33.750252Z","caller":"traceutil/trace.go:171","msg":"trace[1599388146] transaction","detail":"{read_only:false; response_revision:1670; number_of_response:1; }","duration":"118.753247ms","start":"2026-08-24T15:48:33.63148Z","end":"2026-08-24T15:48:33.750234Z","steps":["trace[1599388146] 'process raft request' (duration: 70.31654ms)","trace[1599388146] 'compare' (duration: 48.351804ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:48:33.939875Z","caller":"traceutil/trace.go:171","msg":"trace[1156206426] transaction","detail":"{read_only:false; response_revision:1671; number_of_response:1; }","duration":"114.885928ms","start":"2026-08-24T15:48:33.824948Z","end":"2026-08-24T15:48:33.939834Z","steps":["trace[1156206426] 'process raft request' (duration: 91.06421ms)","trace[1156206426] 'compare' (duration: 23.189644ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:48:39.362238Z","caller":"traceutil/trace.go:171","msg":"trace[1013410655] transaction","detail":"{read_only:false; response_revision:1693; number_of_response:1; }","duration":"180.489932ms","start":"2026-08-24T15:48:39.181706Z","end":"2026-08-24T15:48:39.362196Z","steps":["trace[1013410655] 'process raft request' (duration: 180.199846ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:39.85885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.659753ms","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-08-24T15:48:39.858877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.051858ms","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-08-24T15:48:39.858929Z","caller":"traceutil/trace.go:171","msg":"trace[488822665] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1693; }","duration":"366.788156ms","start":"2026-08-24T15:48:39.492123Z","end":"2026-08-24T15:48:39.858911Z","steps":["trace[488822665] 'range keys from in-memory index tree' (duration: 366.51115ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:48:39.858947Z","caller":"traceutil/trace.go:171","msg":"trace[536661405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1693; }","duration":"392.200451ms","start":"2026-08-24T15:48:39.466728Z","end":"2026-08-24T15:48:39.858929Z","steps":["trace[536661405] 'range keys from in-memory index tree' (duration: 391.812813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:39.858974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:48:39.492095Z","time spent":"366.867828ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-24T15:48:39.858995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:48:39.466705Z","time spent":"392.275402ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:48:39.858992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.935345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-24T15:48:39.859143Z","caller":"traceutil/trace.go:171","msg":"trace[1463161313] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1693; }","duration":"182.17247ms","start":"2026-08-24T15:48:39.676945Z","end":"2026-08-24T15:48:39.859117Z","steps":["trace[1463161313] 'range keys from in-memory index tree' (duration: 181.70002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:39.859448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.317375ms","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-08-24T15:48:39.859622Z","caller":"traceutil/trace.go:171","msg":"trace[205876860] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1693; }","duration":"237.155533ms","start":"2026-08-24T15:48:39.62245Z","end":"2026-08-24T15:48:39.859606Z","steps":["trace[205876860] 'range keys from in-memory index tree' (duration: 236.11161ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:49:24.411961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.919243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:49:24.412273Z","caller":"traceutil/trace.go:171","msg":"trace[1398894993] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"237.458931ms","start":"2026-08-24T15:49:24.174792Z","end":"2026-08-24T15:49:24.412251Z","steps":["trace[1398894993] 'process raft request' (duration: 237.328908ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:49:24.412399Z","caller":"traceutil/trace.go:171","msg":"trace[1043898686] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1976; }","duration":"201.470689ms","start":"2026-08-24T15:49:24.210906Z","end":"2026-08-24T15:49:24.412377Z","steps":["trace[1043898686] 'read index received' (duration: 35.203505ms)","trace[1043898686] 'applied index is now lower than readState.Index' (duration: 166.265504ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:49:24.412413Z","caller":"traceutil/trace.go:171","msg":"trace[414783238] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"237.353028ms","start":"2026-08-24T15:49:24.175039Z","end":"2026-08-24T15:49:24.412392Z","steps":["trace[414783238] 'process raft request' (duration: 237.169034ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:49:24.412395Z","caller":"traceutil/trace.go:171","msg":"trace[1263970306] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"237.608704ms","start":"2026-08-24T15:49:24.174766Z","end":"2026-08-24T15:49:24.412374Z","steps":["trace[1263970306] 'process raft request' (duration: 130.18846ms)","trace[1263970306] 'compare' (duration: 106.741299ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:49:24.412567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.646382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-08-24T15:49:24.412647Z","caller":"traceutil/trace.go:171","msg":"trace[1549604761] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1900; }","duration":"201.723973ms","start":"2026-08-24T15:49:24.210875Z","end":"2026-08-24T15:49:24.412599Z","steps":["trace[1549604761] 'agreement among raft nodes before linearized reading' (duration: 201.57854ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:49:26.641908Z","caller":"traceutil/trace.go:171","msg":"trace[1497744645] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"136.331998ms","start":"2026-08-24T15:49:26.505558Z","end":"2026-08-24T15:49:26.64189Z","steps":["trace[1497744645] 'process raft request' (duration: 136.262877ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:49:26.642085Z","caller":"traceutil/trace.go:171","msg":"trace[189269476] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"137.891752ms","start":"2026-08-24T15:49:26.50418Z","end":"2026-08-24T15:49:26.642072Z","steps":["trace[189269476] 'process raft request' (duration: 64.016215ms)","trace[189269476] 'compare' (duration: 73.395227ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:49:28.59686Z","caller":"traceutil/trace.go:171","msg":"trace[1847913639] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"139.571741ms","start":"2026-08-24T15:49:28.457255Z","end":"2026-08-24T15:49:28.596827Z","steps":["trace[1847913639] 'process raft request' (duration: 119.086679ms)","trace[1847913639] 'compare' (duration: 20.310888ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:49:28.737723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.615217ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-24T15:49:30.519204Z","caller":"traceutil/trace.go:171","msg":"trace[1461195093] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"100.132661ms","start":"2026-08-24T15:49:30.41904Z","end":"2026-08-24T15:49:30.519172Z","steps":["trace[1461195093] 'process raft request' (duration: 69.189513ms)","trace[1461195093] 'compare' (duration: 30.817815ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:49:43.952816Z","caller":"traceutil/trace.go:171","msg":"trace[1773073626] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"153.589147ms","start":"2026-08-24T15:49:43.799206Z","end":"2026-08-24T15:49:43.952795Z","steps":["trace[1773073626] 'process raft request' (duration: 153.443274ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:49:45.2501Z","caller":"traceutil/trace.go:171","msg":"trace[940935692] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"100.783235ms","start":"2026-08-24T15:49:45.14929Z","end":"2026-08-24T15:49:45.250073Z","steps":["trace[940935692] 'process raft request' (duration: 100.593151ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:50:01.860175Z","caller":"traceutil/trace.go:171","msg":"trace[1566882743] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"109.573543ms","start":"2026-08-24T15:50:01.750561Z","end":"2026-08-24T15:50:01.860135Z","steps":["trace[1566882743] 'process raft request' (duration: 109.333968ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:50:15.162744Z","caller":"traceutil/trace.go:171","msg":"trace[844434264] linearizableReadLoop","detail":"{readStateIndex:2242; appliedIndex:2241; }","duration":"144.367716ms","start":"2026-08-24T15:50:15.018352Z","end":"2026-08-24T15:50:15.16272Z","steps":["trace[844434264] 'read index received' (duration: 144.162471ms)","trace[844434264] 'applied index is now lower than readState.Index' (duration: 204.115µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:50:15.162953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.594221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:50:15.163097Z","caller":"traceutil/trace.go:171","msg":"trace[1717504770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2151; }","duration":"144.796916ms","start":"2026-08-24T15:50:15.018287Z","end":"2026-08-24T15:50:15.163084Z","steps":["trace[1717504770] 'agreement among raft nodes before linearized reading' (duration: 144.618102ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:50:15.163221Z","caller":"traceutil/trace.go:171","msg":"trace[2061601597] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"150.749771ms","start":"2026-08-24T15:50:15.012447Z","end":"2026-08-24T15:50:15.163197Z","steps":["trace[2061601597] 'process raft request' (duration: 150.072565ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:50:15.269236Z","caller":"traceutil/trace.go:171","msg":"trace[2080495215] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"100.49149ms","start":"2026-08-24T15:50:15.168722Z","end":"2026-08-24T15:50:15.269214Z","steps":["trace[2080495215] 'process raft request' (duration: 100.308575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:50:43.164763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.632954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/envoy-gateway-system/\" range_end:\"/registry/cronjobs/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:50:43.164828Z","caller":"traceutil/trace.go:171","msg":"trace[1981900428] range","detail":"{range_begin:/registry/cronjobs/envoy-gateway-system/; range_end:/registry/cronjobs/envoy-gateway-system0; response_count:0; response_revision:2291; }","duration":"108.748377ms","start":"2026-08-24T15:50:43.056066Z","end":"2026-08-24T15:50:43.164814Z","steps":["trace[1981900428] 'range keys from in-memory index tree' (duration: 108.522462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:50:49.137482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.817933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/ceph-csi-rbd-nodeplugin-http-metrics\" ","response":"range_response_count:1 size:929"} {"level":"info","ts":"2026-08-24T15:50:49.137581Z","caller":"traceutil/trace.go:171","msg":"trace[902323967] range","detail":"{range_begin:/registry/services/endpoints/kube-system/ceph-csi-rbd-nodeplugin-http-metrics; range_end:; response_count:1; response_revision:2318; }","duration":"143.948676ms","start":"2026-08-24T15:50:48.993613Z","end":"2026-08-24T15:50:49.137561Z","steps":["trace[902323967] 'range keys from in-memory index tree' (duration: 143.654209ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:50:49.137647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.192976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:50:49.137775Z","caller":"traceutil/trace.go:171","msg":"trace[951532124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2318; }","duration":"120.359941ms","start":"2026-08-24T15:50:49.017387Z","end":"2026-08-24T15:50:49.137747Z","steps":["trace[951532124] 'range keys from in-memory index tree' (duration: 120.001392ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:50:49.13789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.720596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4219"} {"level":"info","ts":"2026-08-24T15:50:49.13797Z","caller":"traceutil/trace.go:171","msg":"trace[1915362799] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:2318; }","duration":"239.31544ms","start":"2026-08-24T15:50:48.89864Z","end":"2026-08-24T15:50:49.137955Z","steps":["trace[1915362799] 'range keys from in-memory index tree' (duration: 238.553722ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:50:49.352323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.919667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/ceph-csi-rbd-provisioner-http-metrics\" ","response":"range_response_count:1 size:940"} {"level":"info","ts":"2026-08-24T15:50:49.352416Z","caller":"traceutil/trace.go:171","msg":"trace[1927512732] range","detail":"{range_begin:/registry/services/endpoints/kube-system/ceph-csi-rbd-provisioner-http-metrics; range_end:; response_count:1; response_revision:2319; }","duration":"104.03635ms","start":"2026-08-24T15:50:49.248359Z","end":"2026-08-24T15:50:49.352395Z","steps":["trace[1927512732] 'range keys from in-memory index tree' (duration: 103.713593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:50:57.41645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.570594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/ceph-csi-rbd-provisioner-http-metrics\" ","response":"range_response_count:1 size:1191"} {"level":"info","ts":"2026-08-24T15:50:57.416584Z","caller":"traceutil/trace.go:171","msg":"trace[576516545] range","detail":"{range_begin:/registry/services/specs/kube-system/ceph-csi-rbd-provisioner-http-metrics; range_end:; response_count:1; response_revision:2357; }","duration":"160.759248ms","start":"2026-08-24T15:50:57.255799Z","end":"2026-08-24T15:50:57.416558Z","steps":["trace[576516545] 'range keys from in-memory index tree' (duration: 160.251327ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:51:02.252434Z","caller":"traceutil/trace.go:171","msg":"trace[734377552] transaction","detail":"{read_only:false; response_revision:2381; number_of_response:1; }","duration":"125.549209ms","start":"2026-08-24T15:51:02.126855Z","end":"2026-08-24T15:51:02.252405Z","steps":["trace[734377552] 'process raft request' (duration: 125.310143ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:51:03.355727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.039601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bl8mt\" ","response":"range_response_count:1 size:18521"} {"level":"info","ts":"2026-08-24T15:51:03.355805Z","caller":"traceutil/trace.go:171","msg":"trace[357226434] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bl8mt; range_end:; response_count:1; response_revision:2383; }","duration":"238.176104ms","start":"2026-08-24T15:51:03.117615Z","end":"2026-08-24T15:51:03.355792Z","steps":["trace[357226434] 'range keys from in-memory index tree' (duration: 237.864817ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:51:05.997602Z","caller":"traceutil/trace.go:171","msg":"trace[265308478] linearizableReadLoop","detail":"{readStateIndex:2500; appliedIndex:2499; }","duration":"176.520305ms","start":"2026-08-24T15:51:05.82106Z","end":"2026-08-24T15:51:05.99758Z","steps":["trace[265308478] 'read index received' (duration: 176.31075ms)","trace[265308478] 'applied index is now lower than readState.Index' (duration: 208.465µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:51:05.997696Z","caller":"traceutil/trace.go:171","msg":"trace[496167808] transaction","detail":"{read_only:false; response_revision:2398; number_of_response:1; }","duration":"189.275905ms","start":"2026-08-24T15:51:05.808385Z","end":"2026-08-24T15:51:05.997661Z","steps":["trace[496167808] 'process raft request' (duration: 189.038419ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:51:05.99782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.7453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-7tp7m\" ","response":"range_response_count:1 size:4907"} {"level":"info","ts":"2026-08-24T15:51:05.99787Z","caller":"traceutil/trace.go:171","msg":"trace[1994389206] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-7tp7m; range_end:; response_count:1; response_revision:2398; }","duration":"176.832542ms","start":"2026-08-24T15:51:05.821021Z","end":"2026-08-24T15:51:05.997853Z","steps":["trace[1994389206] 'agreement among raft nodes before linearized reading' (duration: 176.647858ms)"],"step_count":1}