{"level":"warn","ts":"2026-09-22T01:58:48.580876Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T01:58:48.580997Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.241: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://162.253.55.241:2380","--initial-cluster=instance=https://162.253.55.241:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.241:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.241: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-09-22T01:58:48.581139Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T01:58:48.581176Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.241:2380"]} {"level":"info","ts":"2026-09-22T01:58:48.581203Z","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-09-22T01:58:48.582032Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.241:2379"]} {"level":"info","ts":"2026-09-22T01:58:48.582254Z","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://162.253.55.241:2380"],"listen-peer-urls":["https://162.253.55.241:2380"],"advertise-client-urls":["https://162.253.55.241:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.241:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.241: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-09-22T01:58:48.587468Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.897452ms"} {"level":"info","ts":"2026-09-22T01:58:48.597429Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f50d212ec16cc59","cluster-id":"30c503bd66239e72"} {"level":"info","ts":"2026-09-22T01:58:48.597519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 switched to configuration voters=()"} {"level":"info","ts":"2026-09-22T01:58:48.597544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became follower at term 0"} {"level":"info","ts":"2026-09-22T01:58:48.597595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f50d212ec16cc59 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-22T01:58:48.59761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became follower at term 1"} {"level":"info","ts":"2026-09-22T01:58:48.597674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 switched to configuration voters=(6868220410452167769)"} {"level":"warn","ts":"2026-09-22T01:58:48.605649Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-22T01:58:48.606978Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-22T01:58:48.608546Z","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-09-22T01:58:48.610649Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f50d212ec16cc59","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-22T01:58:48.610789Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f50d212ec16cc59","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-22T01:58:48.611178Z","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-09-22T01:58:48.611285Z","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-09-22T01:58:48.611331Z","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-09-22T01:58:48.613888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 switched to configuration voters=(6868220410452167769)"} {"level":"info","ts":"2026-09-22T01:58:48.614296Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"30c503bd66239e72","local-member-id":"5f50d212ec16cc59","added-peer-id":"5f50d212ec16cc59","added-peer-peer-urls":["https://162.253.55.241:2380"]} {"level":"info","ts":"2026-09-22T01:58:48.615373Z","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-09-22T01:58:48.615531Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.241:2380"} {"level":"info","ts":"2026-09-22T01:58:48.615561Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.241:2380"} {"level":"info","ts":"2026-09-22T01:58:48.615721Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f50d212ec16cc59","initial-advertise-peer-urls":["https://162.253.55.241:2380"],"listen-peer-urls":["https://162.253.55.241:2380"],"advertise-client-urls":["https://162.253.55.241:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.241:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-22T01:58:48.615753Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-22T01:58:49.598961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 is starting a new election at term 1"} {"level":"info","ts":"2026-09-22T01:58:49.599053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-22T01:58:49.599124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 received MsgPreVoteResp from 5f50d212ec16cc59 at term 1"} {"level":"info","ts":"2026-09-22T01:58:49.599154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became candidate at term 2"} {"level":"info","ts":"2026-09-22T01:58:49.599187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 received MsgVoteResp from 5f50d212ec16cc59 at term 2"} {"level":"info","ts":"2026-09-22T01:58:49.599203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became leader at term 2"} {"level":"info","ts":"2026-09-22T01:58:49.599221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f50d212ec16cc59 elected leader 5f50d212ec16cc59 at term 2"} {"level":"info","ts":"2026-09-22T01:58:49.600266Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:58:49.601318Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f50d212ec16cc59","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.241:2379]}","request-path":"/0/members/5f50d212ec16cc59/attributes","cluster-id":"30c503bd66239e72","publish-timeout":"7s"} {"level":"info","ts":"2026-09-22T01:58:49.601496Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:58:49.601562Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:58:49.602662Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"30c503bd66239e72","local-member-id":"5f50d212ec16cc59","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:58:49.602773Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:58:49.602803Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:58:49.604594Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.241:2379"} {"level":"info","ts":"2026-09-22T01:58:49.60493Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-22T01:58:49.606567Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-22T01:58:49.606635Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-22T01:58:55.395478Z","caller":"traceutil/trace.go:171","msg":"trace[2034499359] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"109.196058ms","start":"2026-09-22T01:58:55.286058Z","end":"2026-09-22T01:58:55.395254Z","steps":["trace[2034499359] 'process raft request' (duration: 34.373621ms)","trace[2034499359] 'compare' (duration: 74.48734ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:58:55.395569Z","caller":"traceutil/trace.go:171","msg":"trace[493297964] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"107.876769ms","start":"2026-09-22T01:58:55.287667Z","end":"2026-09-22T01:58:55.395544Z","steps":["trace[493297964] 'process raft request' (duration: 107.414089ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:55.395776Z","caller":"traceutil/trace.go:171","msg":"trace[1599607916] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"107.202282ms","start":"2026-09-22T01:58:55.288543Z","end":"2026-09-22T01:58:55.395746Z","steps":["trace[1599607916] 'process raft request' (duration: 106.912356ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:55.396004Z","caller":"traceutil/trace.go:171","msg":"trace[1110413571] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"108.157016ms","start":"2026-09-22T01:58:55.287832Z","end":"2026-09-22T01:58:55.395989Z","steps":["trace[1110413571] 'process raft request' (duration: 107.297446ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:55.396109Z","caller":"traceutil/trace.go:171","msg":"trace[1482869453] transaction","detail":"{read_only:false; number_of_response:0; response_revision:303; }","duration":"108.137854ms","start":"2026-09-22T01:58:55.28796Z","end":"2026-09-22T01:58:55.396098Z","steps":["trace[1482869453] 'process raft request' (duration: 107.201992ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:56.119364Z","caller":"traceutil/trace.go:171","msg":"trace[2098673444] transaction","detail":"{read_only:false; number_of_response:0; response_revision:315; }","duration":"113.307641ms","start":"2026-09-22T01:58:56.006037Z","end":"2026-09-22T01:58:56.119345Z","steps":["trace[2098673444] 'process raft request' (duration: 113.147258ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:58:56.119512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.898112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-22T01:58:56.119587Z","caller":"traceutil/trace.go:171","msg":"trace[1122197478] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:316; }","duration":"102.041545ms","start":"2026-09-22T01:58:56.017538Z","end":"2026-09-22T01:58:56.11958Z","steps":["trace[1122197478] 'agreement among raft nodes before linearized reading' (duration: 101.886012ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:56.119754Z","caller":"traceutil/trace.go:171","msg":"trace[1411950590] transaction","detail":"{read_only:false; number_of_response:0; response_revision:315; }","duration":"113.464505ms","start":"2026-09-22T01:58:56.00628Z","end":"2026-09-22T01:58:56.119744Z","steps":["trace[1411950590] 'process raft request' (duration: 112.969863ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:56.119387Z","caller":"traceutil/trace.go:171","msg":"trace[531055850] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:323; }","duration":"101.80365ms","start":"2026-09-22T01:58:56.017564Z","end":"2026-09-22T01:58:56.119367Z","steps":["trace[531055850] 'read index received' (duration: 63.432369ms)","trace[531055850] 'applied index is now lower than readState.Index' (duration: 38.370301ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:58:56.302128Z","caller":"traceutil/trace.go:171","msg":"trace[1429007383] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"128.886105ms","start":"2026-09-22T01:58:56.173199Z","end":"2026-09-22T01:58:56.302085Z","steps":["trace[1429007383] 'process raft request' (duration: 69.931627ms)","trace[1429007383] 'compare' (duration: 58.741533ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:58:56.504728Z","caller":"traceutil/trace.go:171","msg":"trace[211567211] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"150.004125ms","start":"2026-09-22T01:58:56.354705Z","end":"2026-09-22T01:58:56.504709Z","steps":["trace[211567211] 'process raft request' (duration: 66.907539ms)","trace[211567211] 'compare' (duration: 82.903011ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:58:56.504643Z","caller":"traceutil/trace.go:171","msg":"trace[1593922810] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:332; }","duration":"139.829002ms","start":"2026-09-22T01:58:56.364789Z","end":"2026-09-22T01:58:56.504618Z","steps":["trace[1593922810] 'read index received' (duration: 56.87336ms)","trace[1593922810] 'applied index is now lower than readState.Index' (duration: 82.954842ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:58:56.50502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.195691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-09-22T01:58:56.505163Z","caller":"traceutil/trace.go:171","msg":"trace[1388283064] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:322; }","duration":"140.321755ms","start":"2026-09-22T01:58:56.364763Z","end":"2026-09-22T01:58:56.505085Z","steps":["trace[1388283064] 'agreement among raft nodes before linearized reading' (duration: 140.137521ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:58.704728Z","caller":"traceutil/trace.go:171","msg":"trace[194187170] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"130.006871ms","start":"2026-09-22T01:58:58.574677Z","end":"2026-09-22T01:58:58.704684Z","steps":["trace[194187170] 'process raft request' (duration: 80.488706ms)","trace[194187170] 'compare' (duration: 49.150437ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:58:58.887267Z","caller":"traceutil/trace.go:171","msg":"trace[2110651674] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"134.673486ms","start":"2026-09-22T01:58:58.75257Z","end":"2026-09-22T01:58:58.887243Z","steps":["trace[2110651674] 'process raft request' (duration: 134.452961ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:58.887463Z","caller":"traceutil/trace.go:171","msg":"trace[32138631] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:370; }","duration":"120.936024ms","start":"2026-09-22T01:58:58.766267Z","end":"2026-09-22T01:58:58.887203Z","steps":["trace[32138631] 'read index received' (duration: 30.587304ms)","trace[32138631] 'applied index is now lower than readState.Index' (duration: 90.34754ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:58:58.887601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.306213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-09-22T01:58:58.887745Z","caller":"traceutil/trace.go:171","msg":"trace[657434353] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:361; }","duration":"121.476816ms","start":"2026-09-22T01:58:58.766218Z","end":"2026-09-22T01:58:58.887695Z","steps":["trace[657434353] 'agreement among raft nodes before linearized reading' (duration: 121.159819ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:13.979454Z","caller":"traceutil/trace.go:171","msg":"trace[607952243] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:448; }","duration":"163.817878ms","start":"2026-09-22T01:59:13.815614Z","end":"2026-09-22T01:59:13.979432Z","steps":["trace[607952243] 'read index received' (duration: 163.652735ms)","trace[607952243] 'applied index is now lower than readState.Index' (duration: 164.093µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:59:13.979611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.974191ms","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-09-22T01:59:13.979647Z","caller":"traceutil/trace.go:171","msg":"trace[2039398092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:434; }","duration":"164.058814ms","start":"2026-09-22T01:59:13.815578Z","end":"2026-09-22T01:59:13.979637Z","steps":["trace[2039398092] 'agreement among raft nodes before linearized reading' (duration: 163.952551ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:13.980138Z","caller":"traceutil/trace.go:171","msg":"trace[1767004405] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"194.240048ms","start":"2026-09-22T01:59:13.785888Z","end":"2026-09-22T01:59:13.980128Z","steps":["trace[1767004405] 'process raft request' (duration: 193.43361ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:15.99642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.917253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:59:15.996646Z","caller":"traceutil/trace.go:171","msg":"trace[1400928604] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"211.96811ms","start":"2026-09-22T01:59:15.784669Z","end":"2026-09-22T01:59:15.996637Z","steps":["trace[1400928604] 'process raft request' (duration: 211.894329ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:15.996642Z","caller":"traceutil/trace.go:171","msg":"trace[707299135] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"213.67725ms","start":"2026-09-22T01:59:15.782934Z","end":"2026-09-22T01:59:15.996611Z","steps":["trace[707299135] 'process raft request' (duration: 85.05489ms)","trace[707299135] 'compare' (duration: 127.827601ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:59:15.996794Z","caller":"traceutil/trace.go:171","msg":"trace[806698833] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"213.482385ms","start":"2026-09-22T01:59:15.783267Z","end":"2026-09-22T01:59:15.99675Z","steps":["trace[806698833] 'process raft request' (duration: 213.230779ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:15.996875Z","caller":"traceutil/trace.go:171","msg":"trace[286266677] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"213.194839ms","start":"2026-09-22T01:59:15.783659Z","end":"2026-09-22T01:59:15.996854Z","steps":["trace[286266677] 'process raft request' (duration: 212.875662ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:24.149011Z","caller":"traceutil/trace.go:171","msg":"trace[1412828119] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"104.196075ms","start":"2026-09-22T01:59:24.044789Z","end":"2026-09-22T01:59:24.148985Z","steps":["trace[1412828119] 'process raft request' (duration: 103.97462ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:24.14954Z","caller":"traceutil/trace.go:171","msg":"trace[1702812644] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:504; }","duration":"104.195576ms","start":"2026-09-22T01:59:24.045328Z","end":"2026-09-22T01:59:24.149524Z","steps":["trace[1702812644] 'read index received' (duration: 104.188675ms)","trace[1702812644] 'applied index is now lower than readState.Index' (duration: 5.611µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:59:24.14973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.397859ms","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-09-22T01:59:24.149782Z","caller":"traceutil/trace.go:171","msg":"trace[2085032474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:487; }","duration":"104.533693ms","start":"2026-09-22T01:59:24.045235Z","end":"2026-09-22T01:59:24.149769Z","steps":["trace[2085032474] 'agreement among raft nodes before linearized reading' (duration: 104.351339ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:32.350126Z","caller":"traceutil/trace.go:171","msg":"trace[1826282278] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"162.864447ms","start":"2026-09-22T01:59:32.187244Z","end":"2026-09-22T01:59:32.350109Z","steps":["trace[1826282278] 'read index received' (duration: 162.781625ms)","trace[1826282278] 'applied index is now lower than readState.Index' (duration: 82.192µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:59:32.3502Z","caller":"traceutil/trace.go:171","msg":"trace[1747619044] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"329.627502ms","start":"2026-09-22T01:59:32.020543Z","end":"2026-09-22T01:59:32.35017Z","steps":["trace[1747619044] 'process raft request' (duration: 329.480249ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:32.350289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.175831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-22T01:59:32.350302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.045501ms","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-09-22T01:59:32.350338Z","caller":"traceutil/trace.go:171","msg":"trace[631806885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"151.251742ms","start":"2026-09-22T01:59:32.199079Z","end":"2026-09-22T01:59:32.35033Z","steps":["trace[631806885] 'agreement among raft nodes before linearized reading' (duration: 151.170491ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:32.350353Z","caller":"traceutil/trace.go:171","msg":"trace[1624411486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"163.133413ms","start":"2026-09-22T01:59:32.187205Z","end":"2026-09-22T01:59:32.350338Z","steps":["trace[1624411486] 'agreement among raft nodes before linearized reading' (duration: 163.043611ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:32.350375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:32.020518Z","time spent":"329.769065ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:59:33.358574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.621968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:59:33.358663Z","caller":"traceutil/trace.go:171","msg":"trace[2139731515] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:541; }","duration":"847.776271ms","start":"2026-09-22T01:59:32.510866Z","end":"2026-09-22T01:59:33.358642Z","steps":["trace[2139731515] 'range keys from in-memory index tree' (duration: 847.520765ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:33.35874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:32.510843Z","time spent":"847.886753ms","remote":"127.0.0.1:42762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-09-22T01:59:33.358715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.270007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.241\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-22T01:59:33.35881Z","caller":"traceutil/trace.go:171","msg":"trace[591365926] range","detail":"{range_begin:/registry/masterleases/162.253.55.241; range_end:; response_count:1; response_revision:541; }","duration":"464.4255ms","start":"2026-09-22T01:59:32.894361Z","end":"2026-09-22T01:59:33.358786Z","steps":["trace[591365926] 'range keys from in-memory index tree' (duration: 464.156774ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:33.358862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:32.894341Z","time spent":"464.506812ms","remote":"127.0.0.1:42698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.241\" "} {"level":"warn","ts":"2026-09-22T01:59:40.188781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.050785ms","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-09-22T01:59:40.18886Z","caller":"traceutil/trace.go:171","msg":"trace[1852891321] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:647; }","duration":"532.187268ms","start":"2026-09-22T01:59:39.656654Z","end":"2026-09-22T01:59:40.188841Z","steps":["trace[1852891321] 'range keys from in-memory index tree' (duration: 531.897551ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:40.188893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:39.656636Z","time spent":"532.249419ms","remote":"127.0.0.1:42926","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-09-22T01:59:40.341647Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14724977232523958873,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-22T01:59:40.404629Z","caller":"traceutil/trace.go:171","msg":"trace[827860038] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"703.66208ms","start":"2026-09-22T01:59:39.700899Z","end":"2026-09-22T01:59:40.404561Z","steps":["trace[827860038] 'process raft request' (duration: 698.903013ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:40.405189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:39.70087Z","time spent":"704.201323ms","remote":"127.0.0.1:42762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T01:59:40.437566Z","caller":"traceutil/trace.go:171","msg":"trace[625222802] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:669; }","duration":"596.690192ms","start":"2026-09-22T01:59:39.840814Z","end":"2026-09-22T01:59:40.437504Z","steps":["trace[625222802] 'read index received' (duration: 558.283681ms)","trace[625222802] 'applied index is now lower than readState.Index' (duration: 38.405521ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:59:40.437639Z","caller":"traceutil/trace.go:171","msg":"trace[1203154370] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"245.003401ms","start":"2026-09-22T01:59:40.19261Z","end":"2026-09-22T01:59:40.437613Z","steps":["trace[1203154370] 'process raft request' (duration: 244.772426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:40.437716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.891477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-22T01:59:40.437803Z","caller":"traceutil/trace.go:171","msg":"trace[477912094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"597.01962ms","start":"2026-09-22T01:59:39.840772Z","end":"2026-09-22T01:59:40.437792Z","steps":["trace[477912094] 'agreement among raft nodes before linearized reading' (duration: 596.890257ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:40.437841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:39.840753Z","time spent":"597.078001ms","remote":"127.0.0.1:42926","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-09-22T01:59:40.437867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.435212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-m95g7\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-22T01:59:40.437931Z","caller":"traceutil/trace.go:171","msg":"trace[959462106] range","detail":"{range_begin:/registry/pods/kube-system/cilium-m95g7; range_end:; response_count:1; response_revision:649; }","duration":"305.545434ms","start":"2026-09-22T01:59:40.132368Z","end":"2026-09-22T01:59:40.437914Z","steps":["trace[959462106] 'agreement among raft nodes before linearized reading' (duration: 305.36492ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:40.437998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:40.132349Z","time spent":"305.636776ms","remote":"127.0.0.1:42854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-m95g7\" "} {"level":"warn","ts":"2026-09-22T01:59:40.710839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.619828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:59:40.710955Z","caller":"traceutil/trace.go:171","msg":"trace[2069639352] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"268.800121ms","start":"2026-09-22T01:59:40.442132Z","end":"2026-09-22T01:59:40.710932Z","steps":["trace[2069639352] 'process raft request' (duration: 117.987809ms)","trace[2069639352] 'compare' (duration: 150.346832ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:59:40.79679Z","caller":"traceutil/trace.go:171","msg":"trace[1296726179] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"340.511279ms","start":"2026-09-22T01:59:40.456249Z","end":"2026-09-22T01:59:40.79676Z","steps":["trace[1296726179] 'process raft request' (duration: 340.16513ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:40.796883Z","caller":"traceutil/trace.go:171","msg":"trace[1495652731] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:671; }","duration":"333.434928ms","start":"2026-09-22T01:59:40.463397Z","end":"2026-09-22T01:59:40.796832Z","steps":["trace[1495652731] 'read index received' (duration: 96.732586ms)","trace[1495652731] 'applied index is now lower than readState.Index' (duration: 236.700992ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:59:40.796914Z","caller":"traceutil/trace.go:171","msg":"trace[473955996] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"324.516895ms","start":"2026-09-22T01:59:40.472352Z","end":"2026-09-22T01:59:40.796869Z","steps":["trace[473955996] 'process raft request' (duration: 324.219477ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:40.796937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:40.456206Z","time spent":"340.661392ms","remote":"127.0.0.1:42854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:59:40.797056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:40.472336Z","time spent":"324.639858ms","remote":"127.0.0.1:43152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T01:59:40.797193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.784716ms","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-09-22T01:59:40.797255Z","caller":"traceutil/trace.go:171","msg":"trace[1940998072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:652; }","duration":"333.871508ms","start":"2026-09-22T01:59:40.463367Z","end":"2026-09-22T01:59:40.797239Z","steps":["trace[1940998072] 'agreement among raft nodes before linearized reading' (duration: 333.623393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:40.797286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:40.463355Z","time spent":"333.923439ms","remote":"127.0.0.1:42926","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-09-22T01:59:40.797285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.437066ms","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-09-22T01:59:40.79735Z","caller":"traceutil/trace.go:171","msg":"trace[1205510974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:652; }","duration":"332.601249ms","start":"2026-09-22T01:59:40.464727Z","end":"2026-09-22T01:59:40.797329Z","steps":["trace[1205510974] 'agreement among raft nodes before linearized reading' (duration: 332.279292ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:40.797473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:40.464718Z","time spent":"332.738432ms","remote":"127.0.0.1:42926","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-09-22T01:59:41.471108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.521422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:59:41.471317Z","caller":"traceutil/trace.go:171","msg":"trace[278370757] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"670.003166ms","start":"2026-09-22T01:59:40.801295Z","end":"2026-09-22T01:59:41.471298Z","steps":["trace[278370757] 'process raft request' (duration: 190.218878ms)","trace[278370757] 'compare' (duration: 479.186805ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:59:41.4715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:40.801271Z","time spent":"670.19407ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T01:59:41.498968Z","caller":"traceutil/trace.go:171","msg":"trace[1985953941] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:674; }","duration":"687.80261ms","start":"2026-09-22T01:59:40.811142Z","end":"2026-09-22T01:59:41.498944Z","steps":["trace[1985953941] 'read index received' (duration: 180.521207ms)","trace[1985953941] 'applied index is now lower than readState.Index' (duration: 507.280173ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:59:41.49903Z","caller":"traceutil/trace.go:171","msg":"trace[108993268] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"697.180923ms","start":"2026-09-22T01:59:40.801809Z","end":"2026-09-22T01:59:41.49899Z","steps":["trace[108993268] 'process raft request' (duration: 697.006039ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:41.499096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.929683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-22T01:59:41.499163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.799047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-22T01:59:41.499177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:40.801796Z","time spent":"697.300466ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T01:59:41.49925Z","caller":"traceutil/trace.go:171","msg":"trace[1460191541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"497.875059ms","start":"2026-09-22T01:59:41.001334Z","end":"2026-09-22T01:59:41.499209Z","steps":["trace[1460191541] 'agreement among raft nodes before linearized reading' (duration: 497.772847ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:41.499319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:41.00127Z","time spent":"498.020423ms","remote":"127.0.0.1:42652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-22T01:59:41.499271Z","caller":"traceutil/trace.go:171","msg":"trace[1839379036] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:654; }","duration":"688.088947ms","start":"2026-09-22T01:59:40.811112Z","end":"2026-09-22T01:59:41.499201Z","steps":["trace[1839379036] 'agreement among raft nodes before linearized reading' (duration: 687.910203ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:41.499391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:40.811103Z","time spent":"688.273901ms","remote":"127.0.0.1:42904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-09-22T01:59:42.503034Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14724977232523958887,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-22T01:59:42.687352Z","caller":"traceutil/trace.go:171","msg":"trace[2100596750] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"685.238533ms","start":"2026-09-22T01:59:42.002091Z","end":"2026-09-22T01:59:42.687329Z","steps":["trace[2100596750] 'read index received' (duration: 684.487726ms)","trace[2100596750] 'applied index is now lower than readState.Index' (duration: 749.827µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:59:42.687445Z","caller":"traceutil/trace.go:171","msg":"trace[1951877012] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"804.863218ms","start":"2026-09-22T01:59:41.882572Z","end":"2026-09-22T01:59:42.687436Z","steps":["trace[1951877012] 'process raft request' (duration: 804.248863ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:42.687544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:41.882542Z","time spent":"804.928399ms","remote":"127.0.0.1:42762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:59:42.687658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.511359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:59:42.687795Z","caller":"traceutil/trace.go:171","msg":"trace[2087714446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"685.673352ms","start":"2026-09-22T01:59:42.002077Z","end":"2026-09-22T01:59:42.687751Z","steps":["trace[2087714446] 'agreement among raft nodes before linearized reading' (duration: 685.429247ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:42.687882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:42.002023Z","time spent":"685.823436ms","remote":"127.0.0.1:42652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T01:59:43.188012Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14724977232523958890,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-22T01:59:43.382665Z","caller":"traceutil/trace.go:171","msg":"trace[617231076] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"712.707096ms","start":"2026-09-22T01:59:42.669934Z","end":"2026-09-22T01:59:43.382641Z","steps":["trace[617231076] 'process raft request' (duration: 712.563083ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:43.382806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:42.66991Z","time spent":"712.818029ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T01:59:43.38969Z","caller":"traceutil/trace.go:171","msg":"trace[352833091] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"606.459144ms","start":"2026-09-22T01:59:42.783205Z","end":"2026-09-22T01:59:43.389664Z","steps":["trace[352833091] 'process raft request' (duration: 606.29206ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:43.389842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:42.783182Z","time spent":"606.592018ms","remote":"127.0.0.1:42904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:59:43.390013Z","caller":"traceutil/trace.go:171","msg":"trace[2079955243] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:677; }","duration":"702.590936ms","start":"2026-09-22T01:59:42.68741Z","end":"2026-09-22T01:59:43.39Z","steps":["trace[2079955243] 'read index received' (duration: 695.072185ms)","trace[2079955243] 'applied index is now lower than readState.Index' (duration: 7.517771ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:59:43.390173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.280072ms","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-09-22T01:59:43.390175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.51808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:59:43.390212Z","caller":"traceutil/trace.go:171","msg":"trace[1211197191] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:657; }","duration":"948.362404ms","start":"2026-09-22T01:59:42.441839Z","end":"2026-09-22T01:59:43.390202Z","steps":["trace[1211197191] 'agreement among raft nodes before linearized reading' (duration: 948.236091ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:43.390252Z","caller":"traceutil/trace.go:171","msg":"trace[2078852558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:657; }","duration":"700.591581ms","start":"2026-09-22T01:59:42.689617Z","end":"2026-09-22T01:59:43.390208Z","steps":["trace[2078852558] 'agreement among raft nodes before linearized reading' (duration: 700.518889ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:43.390265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:42.441819Z","time spent":"948.438237ms","remote":"127.0.0.1:42926","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-09-22T01:59:43.390286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:42.689602Z","time spent":"700.677143ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T01:59:43.390502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.973357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.241\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-22T01:59:43.390534Z","caller":"traceutil/trace.go:171","msg":"trace[1623141426] range","detail":"{range_begin:/registry/masterleases/162.253.55.241; range_end:; response_count:1; response_revision:657; }","duration":"496.030878ms","start":"2026-09-22T01:59:42.894495Z","end":"2026-09-22T01:59:43.390526Z","steps":["trace[1623141426] 'agreement among raft nodes before linearized reading' (duration: 495.921725ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:43.390532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.984491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-22T01:59:43.390563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:42.894483Z","time spent":"496.073568ms","remote":"127.0.0.1:42698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.241\" "} {"level":"info","ts":"2026-09-22T01:59:43.390569Z","caller":"traceutil/trace.go:171","msg":"trace[546219037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:657; }","duration":"668.059702ms","start":"2026-09-22T01:59:42.722501Z","end":"2026-09-22T01:59:43.39056Z","steps":["trace[546219037] 'agreement among raft nodes before linearized reading' (duration: 667.998831ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:43.390597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:42.722417Z","time spent":"668.170845ms","remote":"127.0.0.1:42926","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-09-22T01:59:46.624509Z","caller":"traceutil/trace.go:171","msg":"trace[1718408259] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"176.467825ms","start":"2026-09-22T01:59:46.447981Z","end":"2026-09-22T01:59:46.624449Z","steps":["trace[1718408259] 'process raft request' (duration: 101.275658ms)","trace[1718408259] 'compare' (duration: 75.022383ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:59:46.83754Z","caller":"traceutil/trace.go:171","msg":"trace[1072981320] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"128.964286ms","start":"2026-09-22T01:59:46.70855Z","end":"2026-09-22T01:59:46.837514Z","steps":["trace[1072981320] 'process raft request' (duration: 128.786983ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:53.389358Z","caller":"traceutil/trace.go:171","msg":"trace[1365140696] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"106.139099ms","start":"2026-09-22T01:59:53.283187Z","end":"2026-09-22T01:59:53.389326Z","steps":["trace[1365140696] 'process raft request' (duration: 59.359724ms)","trace[1365140696] 'compare' (duration: 46.570001ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:59:53.659692Z","caller":"traceutil/trace.go:171","msg":"trace[1966501062] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"104.061284ms","start":"2026-09-22T01:59:53.555608Z","end":"2026-09-22T01:59:53.659669Z","steps":["trace[1966501062] 'process raft request' (duration: 95.649988ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:56.856995Z","caller":"traceutil/trace.go:171","msg":"trace[1983232220] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"259.596729ms","start":"2026-09-22T01:59:56.597376Z","end":"2026-09-22T01:59:56.856973Z","steps":["trace[1983232220] 'process raft request' (duration: 259.434945ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:59:59.920293Z","caller":"traceutil/trace.go:171","msg":"trace[310526518] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"374.329479ms","start":"2026-09-22T01:59:59.54594Z","end":"2026-09-22T01:59:59.92027Z","steps":["trace[310526518] 'process raft request' (duration: 374.170355ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:59:59.920441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:59.545923Z","time spent":"374.446671ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:00:00.473527Z","caller":"traceutil/trace.go:171","msg":"trace[537912329] linearizableReadLoop","detail":"{readStateIndex:852; appliedIndex:850; }","duration":"914.349887ms","start":"2026-09-22T01:59:59.559155Z","end":"2026-09-22T02:00:00.473505Z","steps":["trace[537912329] 'read index received' (duration: 361.043935ms)","trace[537912329] 'applied index is now lower than readState.Index' (duration: 553.304832ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:00:00.473632Z","caller":"traceutil/trace.go:171","msg":"trace[823623036] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"927.19484ms","start":"2026-09-22T01:59:59.546427Z","end":"2026-09-22T02:00:00.473622Z","steps":["trace[823623036] 'process raft request' (duration: 926.520406ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:00.473726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:59.546414Z","time spent":"927.240042ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:00:00.473849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.675943ms","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-09-22T02:00:00.473934Z","caller":"traceutil/trace.go:171","msg":"trace[977388636] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:822; }","duration":"914.785405ms","start":"2026-09-22T01:59:59.559126Z","end":"2026-09-22T02:00:00.473911Z","steps":["trace[977388636] 'agreement among raft nodes before linearized reading' (duration: 914.564201ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:00.473981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:59.559073Z","time spent":"914.897178ms","remote":"127.0.0.1:42926","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-09-22T02:00:00.477491Z","caller":"traceutil/trace.go:171","msg":"trace[1434121569] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"219.055903ms","start":"2026-09-22T02:00:00.258402Z","end":"2026-09-22T02:00:00.477458Z","steps":["trace[1434121569] 'process raft request' (duration: 218.877399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:00.477545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.744365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:00:00.477566Z","caller":"traceutil/trace.go:171","msg":"trace[1657906417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:823; }","duration":"475.801296ms","start":"2026-09-22T02:00:00.001759Z","end":"2026-09-22T02:00:00.47756Z","steps":["trace[1657906417] 'agreement among raft nodes before linearized reading' (duration: 475.762695ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:00.47758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:00:00.00174Z","time spent":"475.836777ms","remote":"127.0.0.1:42652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T02:00:00.477601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.933302ms","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-09-22T02:00:00.477784Z","caller":"traceutil/trace.go:171","msg":"trace[228270696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:823; }","duration":"781.041415ms","start":"2026-09-22T01:59:59.696607Z","end":"2026-09-22T02:00:00.477649Z","steps":["trace[228270696] 'agreement among raft nodes before linearized reading' (duration: 780.8239ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:00.477866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:59:59.696589Z","time spent":"781.263199ms","remote":"127.0.0.1:42926","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-09-22T02:00:03.367692Z","caller":"traceutil/trace.go:171","msg":"trace[1429248844] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"102.219143ms","start":"2026-09-22T02:00:03.26545Z","end":"2026-09-22T02:00:03.367669Z","steps":["trace[1429248844] 'process raft request' (duration: 102.07168ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:09.45018Z","caller":"traceutil/trace.go:171","msg":"trace[701098878] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"108.448911ms","start":"2026-09-22T02:00:09.341692Z","end":"2026-09-22T02:00:09.45014Z","steps":["trace[701098878] 'read index received' (duration: 76.654018ms)","trace[701098878] 'applied index is now lower than readState.Index' (duration: 31.793913ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:00:09.450321Z","caller":"traceutil/trace.go:171","msg":"trace[2075400540] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"109.923503ms","start":"2026-09-22T02:00:09.34037Z","end":"2026-09-22T02:00:09.450293Z","steps":["trace[2075400540] 'process raft request' (duration: 77.890844ms)","trace[2075400540] 'compare' (duration: 31.627231ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:00:09.450522Z","caller":"traceutil/trace.go:171","msg":"trace[1969292868] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"108.288128ms","start":"2026-09-22T02:00:09.342224Z","end":"2026-09-22T02:00:09.450512Z","steps":["trace[1969292868] 'process raft request' (duration: 108.184025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:09.450538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.82533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-brskw\" ","response":"range_response_count:1 size:4777"} {"level":"info","ts":"2026-09-22T02:00:09.450648Z","caller":"traceutil/trace.go:171","msg":"trace[1111719399] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-brskw; range_end:; response_count:1; response_revision:915; }","duration":"108.979174ms","start":"2026-09-22T02:00:09.341656Z","end":"2026-09-22T02:00:09.450635Z","steps":["trace[1111719399] 'agreement among raft nodes before linearized reading' (duration: 108.690287ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:16.839507Z","caller":"traceutil/trace.go:171","msg":"trace[332686413] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"136.962853ms","start":"2026-09-22T02:00:16.702512Z","end":"2026-09-22T02:00:16.839475Z","steps":["trace[332686413] 'process raft request' (duration: 115.552979ms)","trace[332686413] 'compare' (duration: 21.100257ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:00:16.840007Z","caller":"traceutil/trace.go:171","msg":"trace[1426584116] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"137.076746ms","start":"2026-09-22T02:00:16.702901Z","end":"2026-09-22T02:00:16.839978Z","steps":["trace[1426584116] 'process raft request' (duration: 136.919982ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:34.168215Z","caller":"traceutil/trace.go:171","msg":"trace[494267257] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"119.413755ms","start":"2026-09-22T02:00:34.048759Z","end":"2026-09-22T02:00:34.168172Z","steps":["trace[494267257] 'process raft request' (duration: 119.122728ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:41.281772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.269164ms","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-09-22T02:00:41.281852Z","caller":"traceutil/trace.go:171","msg":"trace[204571427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1104; }","duration":"284.395877ms","start":"2026-09-22T02:00:40.997437Z","end":"2026-09-22T02:00:41.281833Z","steps":["trace[204571427] 'range keys from in-memory index tree' (duration: 284.09124ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:41.281987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.258181ms","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-09-22T02:00:41.281994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.344508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-22T02:00:41.281995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.44054ms","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-09-22T02:00:41.282067Z","caller":"traceutil/trace.go:171","msg":"trace[1640635803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1104; }","duration":"147.403224ms","start":"2026-09-22T02:00:41.134643Z","end":"2026-09-22T02:00:41.282047Z","steps":["trace[1640635803] 'range keys from in-memory index tree' (duration: 147.082667ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:41.282085Z","caller":"traceutil/trace.go:171","msg":"trace[209579544] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1104; }","duration":"611.536822ms","start":"2026-09-22T02:00:40.670534Z","end":"2026-09-22T02:00:41.282071Z","steps":["trace[209579544] 'range keys from in-memory index tree' (duration: 611.43031ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:41.282088Z","caller":"traceutil/trace.go:171","msg":"trace[305126809] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1104; }","duration":"914.47159ms","start":"2026-09-22T02:00:40.367595Z","end":"2026-09-22T02:00:41.282067Z","steps":["trace[305126809] 'count revisions from in-memory index tree' (duration: 914.192534ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:41.282104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.93871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-22T02:00:41.282133Z","caller":"traceutil/trace.go:171","msg":"trace[2143398881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1104; }","duration":"388.004592ms","start":"2026-09-22T02:00:40.894119Z","end":"2026-09-22T02:00:41.282124Z","steps":["trace[2143398881] 'range keys from in-memory index tree' (duration: 387.837488ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:41.282134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:00:40.367532Z","time spent":"914.591583ms","remote":"127.0.0.1:43050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:00:41.282158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:00:40.894105Z","time spent":"388.045332ms","remote":"127.0.0.1:42926","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-09-22T02:00:41.621598Z","caller":"traceutil/trace.go:171","msg":"trace[946475282] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"335.742605ms","start":"2026-09-22T02:00:41.28582Z","end":"2026-09-22T02:00:41.621563Z","steps":["trace[946475282] 'process raft request' (duration: 335.521009ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:41.621792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:00:41.285797Z","time spent":"335.897589ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:00:41.629463Z","caller":"traceutil/trace.go:171","msg":"trace[1375303475] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1145; }","duration":"262.264688ms","start":"2026-09-22T02:00:41.367165Z","end":"2026-09-22T02:00:41.629429Z","steps":["trace[1375303475] 'read index received' (duration: 254.464005ms)","trace[1375303475] 'applied index is now lower than readState.Index' (duration: 7.798363ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:00:41.629657Z","caller":"traceutil/trace.go:171","msg":"trace[1337800377] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"343.130348ms","start":"2026-09-22T02:00:41.286505Z","end":"2026-09-22T02:00:41.629636Z","steps":["trace[1337800377] 'process raft request' (duration: 342.582926ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:41.629749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:00:41.286467Z","time spent":"343.241671ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:00:41.629778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.586924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-22T02:00:41.629829Z","caller":"traceutil/trace.go:171","msg":"trace[1679375614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1107; }","duration":"262.716477ms","start":"2026-09-22T02:00:41.3671Z","end":"2026-09-22T02:00:41.629816Z","steps":["trace[1679375614] 'agreement among raft nodes before linearized reading' (duration: 262.481652ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:41.630082Z","caller":"traceutil/trace.go:171","msg":"trace[1938937757] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"338.482495ms","start":"2026-09-22T02:00:41.291586Z","end":"2026-09-22T02:00:41.630069Z","steps":["trace[1938937757] 'process raft request' (duration: 337.770379ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:41.630164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:00:41.291571Z","time spent":"338.553177ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:00:43.502043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14724977232523959890,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-22T02:00:43.522954Z","caller":"traceutil/trace.go:171","msg":"trace[979292672] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1150; }","duration":"521.021069ms","start":"2026-09-22T02:00:43.001911Z","end":"2026-09-22T02:00:43.522932Z","steps":["trace[979292672] 'read index received' (duration: 520.67137ms)","trace[979292672] 'applied index is now lower than readState.Index' (duration: 348.368µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:00:43.523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:00:42.898817Z","time spent":"624.175513ms","remote":"127.0.0.1:42698","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-22T02:00:43.523103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.185092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:00:43.523135Z","caller":"traceutil/trace.go:171","msg":"trace[1286573498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1110; }","duration":"521.273454ms","start":"2026-09-22T02:00:43.001853Z","end":"2026-09-22T02:00:43.523126Z","steps":["trace[1286573498] 'agreement among raft nodes before linearized reading' (duration: 521.193982ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:43.523167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:00:43.001833Z","time spent":"521.326465ms","remote":"127.0.0.1:42652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T02:00:43.523209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.427913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T02:00:43.523404Z","caller":"traceutil/trace.go:171","msg":"trace[594910349] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1110; }","duration":"193.619288ms","start":"2026-09-22T02:00:43.329697Z","end":"2026-09-22T02:00:43.523316Z","steps":["trace[594910349] 'agreement among raft nodes before linearized reading' (duration: 193.401413ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:44.026106Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14724977232523959894,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-22T02:00:44.076211Z","caller":"traceutil/trace.go:171","msg":"trace[631371007] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"551.889671ms","start":"2026-09-22T02:00:43.524295Z","end":"2026-09-22T02:00:44.076184Z","steps":["trace[631371007] 'process raft request' (duration: 551.675736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:44.076403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:00:43.524243Z","time spent":"552.080284ms","remote":"127.0.0.1:42698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:00:44.077719Z","caller":"traceutil/trace.go:171","msg":"trace[731570147] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1151; }","duration":"552.621657ms","start":"2026-09-22T02:00:43.525077Z","end":"2026-09-22T02:00:44.077699Z","steps":["trace[731570147] 'read index received' (duration: 551.005691ms)","trace[731570147] 'applied index is now lower than readState.Index' (duration: 1.614836ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:00:44.077792Z","caller":"traceutil/trace.go:171","msg":"trace[1187206113] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"176.497439ms","start":"2026-09-22T02:00:43.901272Z","end":"2026-09-22T02:00:44.077769Z","steps":["trace[1187206113] 'process raft request' (duration: 176.296624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:44.077885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.778001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-22T02:00:44.077948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.719055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-22T02:00:44.077986Z","caller":"traceutil/trace.go:171","msg":"trace[1398632863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1112; }","duration":"452.798726ms","start":"2026-09-22T02:00:43.625176Z","end":"2026-09-22T02:00:44.077974Z","steps":["trace[1398632863] 'agreement among raft nodes before linearized reading' (duration: 452.640283ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:00:44.077986Z","caller":"traceutil/trace.go:171","msg":"trace[134038062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1112; }","duration":"552.913104ms","start":"2026-09-22T02:00:43.525044Z","end":"2026-09-22T02:00:44.077957Z","steps":["trace[134038062] 'agreement among raft nodes before linearized reading' (duration: 552.74847ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:44.07803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:00:43.625163Z","time spent":"452.847608ms","remote":"127.0.0.1:42926","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-09-22T02:00:44.078048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:00:43.525032Z","time spent":"553.006965ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T02:00:44.07807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.403419ms","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-09-22T02:00:44.077992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.875838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-22T02:00:44.0781Z","caller":"traceutil/trace.go:171","msg":"trace[1173364543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1112; }","duration":"443.46764ms","start":"2026-09-22T02:00:43.634623Z","end":"2026-09-22T02:00:44.07809Z","steps":["trace[1173364543] 'agreement among raft nodes before linearized reading' (duration: 443.400769ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:44.078132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:00:43.634603Z","time spent":"443.523101ms","remote":"127.0.0.1:42926","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-09-22T02:00:44.078126Z","caller":"traceutil/trace.go:171","msg":"trace[1355456562] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1112; }","duration":"434.024611ms","start":"2026-09-22T02:00:43.644078Z","end":"2026-09-22T02:00:44.078102Z","steps":["trace[1355456562] 'agreement among raft nodes before linearized reading' (duration: 433.816876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:44.078201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:00:43.644064Z","time spent":"434.111843ms","remote":"127.0.0.1:42926","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-09-22T02:00:44.078287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.690495ms","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-09-22T02:00:44.078322Z","caller":"traceutil/trace.go:171","msg":"trace[482751972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1112; }","duration":"443.772197ms","start":"2026-09-22T02:00:43.634539Z","end":"2026-09-22T02:00:44.078312Z","steps":["trace[482751972] 'agreement among raft nodes before linearized reading' (duration: 443.669255ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:00:44.078351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:00:43.634529Z","time spent":"443.815458ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-22T02:00:56.489138Z","caller":"traceutil/trace.go:171","msg":"trace[1804462374] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"145.511065ms","start":"2026-09-22T02:00:56.343576Z","end":"2026-09-22T02:00:56.489087Z","steps":["trace[1804462374] 'process raft request' (duration: 145.376182ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:02.663434Z","caller":"traceutil/trace.go:171","msg":"trace[1522736407] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1235; }","duration":"422.872791ms","start":"2026-09-22T02:01:02.24054Z","end":"2026-09-22T02:01:02.663413Z","steps":["trace[1522736407] 'read index received' (duration: 422.651696ms)","trace[1522736407] 'applied index is now lower than readState.Index' (duration: 220.315µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:01:02.663507Z","caller":"traceutil/trace.go:171","msg":"trace[917586125] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"498.17424ms","start":"2026-09-22T02:01:02.165265Z","end":"2026-09-22T02:01:02.66346Z","steps":["trace[917586125] 'process raft request' (duration: 497.994426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:02.663645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.095176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-22T02:01:02.663673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:02.165241Z","time spent":"498.336864ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:01:02.663681Z","caller":"traceutil/trace.go:171","msg":"trace[195561384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1191; }","duration":"423.179698ms","start":"2026-09-22T02:01:02.240488Z","end":"2026-09-22T02:01:02.663668Z","steps":["trace[195561384] 'agreement among raft nodes before linearized reading' (duration: 423.046965ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:02.663705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.92475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-09-22T02:01:02.663721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:02.240469Z","time spent":"423.244119ms","remote":"127.0.0.1:42926","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-09-22T02:01:02.663741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.310099ms","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-09-22T02:01:02.66375Z","caller":"traceutil/trace.go:171","msg":"trace[1667618572] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1191; }","duration":"396.018722ms","start":"2026-09-22T02:01:02.26772Z","end":"2026-09-22T02:01:02.663739Z","steps":["trace[1667618572] 'agreement among raft nodes before linearized reading' (duration: 395.890619ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:02.663784Z","caller":"traceutil/trace.go:171","msg":"trace[173501922] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1191; }","duration":"392.37404ms","start":"2026-09-22T02:01:02.271397Z","end":"2026-09-22T02:01:02.663771Z","steps":["trace[173501922] 'agreement among raft nodes before linearized reading' (duration: 392.265198ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:02.663787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:02.26769Z","time spent":"396.088773ms","remote":"127.0.0.1:42926","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-09-22T02:01:02.663817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:02.271387Z","time spent":"392.422622ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-22T02:01:02.663817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.383539ms","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-09-22T02:01:02.663873Z","caller":"traceutil/trace.go:171","msg":"trace[891759381] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1191; }","duration":"109.485532ms","start":"2026-09-22T02:01:02.554372Z","end":"2026-09-22T02:01:02.663858Z","steps":["trace[891759381] 'agreement among raft nodes before linearized reading' (duration: 109.275837ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:02.664262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.295061ms","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-09-22T02:01:02.66432Z","caller":"traceutil/trace.go:171","msg":"trace[285849323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1191; }","duration":"384.372733ms","start":"2026-09-22T02:01:02.279937Z","end":"2026-09-22T02:01:02.664309Z","steps":["trace[285849323] 'agreement among raft nodes before linearized reading' (duration: 384.025354ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:02.664346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:02.279927Z","time spent":"384.412773ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-22T02:01:02.844165Z","caller":"traceutil/trace.go:171","msg":"trace[719743324] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"174.852859ms","start":"2026-09-22T02:01:02.669285Z","end":"2026-09-22T02:01:02.844138Z","steps":["trace[719743324] 'process raft request' (duration: 79.539664ms)","trace[719743324] 'compare' (duration: 95.219633ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:01:04.816093Z","caller":"traceutil/trace.go:171","msg":"trace[1959864403] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1244; }","duration":"118.127524ms","start":"2026-09-22T02:01:04.697942Z","end":"2026-09-22T02:01:04.816069Z","steps":["trace[1959864403] 'read index received' (duration: 117.93526ms)","trace[1959864403] 'applied index is now lower than readState.Index' (duration: 191.454µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:01:04.816135Z","caller":"traceutil/trace.go:171","msg":"trace[36397613] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"146.948476ms","start":"2026-09-22T02:01:04.669157Z","end":"2026-09-22T02:01:04.816106Z","steps":["trace[36397613] 'process raft request' (duration: 146.776133ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:04.816318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.354449ms","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-09-22T02:01:04.816359Z","caller":"traceutil/trace.go:171","msg":"trace[1821251303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1199; }","duration":"118.447702ms","start":"2026-09-22T02:01:04.6979Z","end":"2026-09-22T02:01:04.816348Z","steps":["trace[1821251303] 'agreement among raft nodes before linearized reading' (duration: 118.280508ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:04.955156Z","caller":"traceutil/trace.go:171","msg":"trace[2112604682] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"131.716638ms","start":"2026-09-22T02:01:04.823413Z","end":"2026-09-22T02:01:04.95513Z","steps":["trace[2112604682] 'process raft request' (duration: 131.467612ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:04.993409Z","caller":"traceutil/trace.go:171","msg":"trace[249414599] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"168.65736ms","start":"2026-09-22T02:01:04.824729Z","end":"2026-09-22T02:01:04.993386Z","steps":["trace[249414599] 'process raft request' (duration: 168.453916ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:04.993536Z","caller":"traceutil/trace.go:171","msg":"trace[617556628] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"168.521447ms","start":"2026-09-22T02:01:04.825001Z","end":"2026-09-22T02:01:04.993522Z","steps":["trace[617556628] 'process raft request' (duration: 168.340393ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:04.993561Z","caller":"traceutil/trace.go:171","msg":"trace[413552869] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"167.884134ms","start":"2026-09-22T02:01:04.825661Z","end":"2026-09-22T02:01:04.993545Z","steps":["trace[413552869] 'process raft request' (duration: 167.70515ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:04.993585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.594007ms","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-09-22T02:01:04.993406Z","caller":"traceutil/trace.go:171","msg":"trace[938945779] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1245; }","duration":"145.415533ms","start":"2026-09-22T02:01:04.847971Z","end":"2026-09-22T02:01:04.993387Z","steps":["trace[938945779] 'read index received' (duration: 106.866284ms)","trace[938945779] 'applied index is now lower than readState.Index' (duration: 38.548219ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:01:04.993637Z","caller":"traceutil/trace.go:171","msg":"trace[1666434461] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1204; }","duration":"145.662619ms","start":"2026-09-22T02:01:04.847965Z","end":"2026-09-22T02:01:04.993628Z","steps":["trace[1666434461] 'agreement among raft nodes before linearized reading' (duration: 145.490145ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:04.993668Z","caller":"traceutil/trace.go:171","msg":"trace[871711018] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"168.749732ms","start":"2026-09-22T02:01:04.824911Z","end":"2026-09-22T02:01:04.993661Z","steps":["trace[871711018] 'process raft request' (duration: 168.400555ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:05.131916Z","caller":"traceutil/trace.go:171","msg":"trace[715608405] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1250; }","duration":"138.228802ms","start":"2026-09-22T02:01:04.993668Z","end":"2026-09-22T02:01:05.131897Z","steps":["trace[715608405] 'read index received' (duration: 138.165541ms)","trace[715608405] 'applied index is now lower than readState.Index' (duration: 62.521µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:01:05.132038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.505806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:01:05.132067Z","caller":"traceutil/trace.go:171","msg":"trace[1626307036] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1205; }","duration":"202.65361ms","start":"2026-09-22T02:01:04.929406Z","end":"2026-09-22T02:01:05.132059Z","steps":["trace[1626307036] 'agreement among raft nodes before linearized reading' (duration: 202.567078ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:05.132326Z","caller":"traceutil/trace.go:171","msg":"trace[535880447] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"165.140832ms","start":"2026-09-22T02:01:04.967176Z","end":"2026-09-22T02:01:05.132317Z","steps":["trace[535880447] 'process raft request' (duration: 164.620071ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:05.143728Z","caller":"traceutil/trace.go:171","msg":"trace[1622304536] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"145.949525ms","start":"2026-09-22T02:01:04.997762Z","end":"2026-09-22T02:01:05.143712Z","steps":["trace[1622304536] 'process raft request' (duration: 145.843193ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:07.218579Z","caller":"traceutil/trace.go:171","msg":"trace[1177398683] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"396.511262ms","start":"2026-09-22T02:01:06.822043Z","end":"2026-09-22T02:01:07.218554Z","steps":["trace[1177398683] 'process raft request' (duration: 396.335799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:07.218717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:06.822014Z","time spent":"396.634435ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:01:07.573158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.546019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T02:01:07.573228Z","caller":"traceutil/trace.go:171","msg":"trace[172767141] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1257; }","duration":"575.741161ms","start":"2026-09-22T02:01:06.997475Z","end":"2026-09-22T02:01:07.573216Z","steps":["trace[172767141] 'read index received' (duration: 221.023229ms)","trace[172767141] 'applied index is now lower than readState.Index' (duration: 354.717162ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:01:07.573299Z","caller":"traceutil/trace.go:171","msg":"trace[825941031] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"653.753859ms","start":"2026-09-22T02:01:06.919538Z","end":"2026-09-22T02:01:07.573292Z","steps":["trace[825941031] 'process raft request' (duration: 432.986325ms)","trace[825941031] 'compare' (duration: 220.444556ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:01:07.573333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:06.919512Z","time spent":"653.805751ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:01:07.573581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.830353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T02:01:07.573653Z","caller":"traceutil/trace.go:171","msg":"trace[1915679854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1213; }","duration":"571.926495ms","start":"2026-09-22T02:01:07.001709Z","end":"2026-09-22T02:01:07.573635Z","steps":["trace[1915679854] 'agreement among raft nodes before linearized reading' (duration: 571.809152ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:07.573673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.11827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-22T02:01:07.573653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.157499ms","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-09-22T02:01:07.573696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:07.001701Z","time spent":"571.987376ms","remote":"127.0.0.1:42652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T02:01:07.573735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.101618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-22T02:01:07.57373Z","caller":"traceutil/trace.go:171","msg":"trace[2010224027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1213; }","duration":"576.280492ms","start":"2026-09-22T02:01:06.997432Z","end":"2026-09-22T02:01:07.573712Z","steps":["trace[2010224027] 'agreement among raft nodes before linearized reading' (duration: 576.040427ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:07.573754Z","caller":"traceutil/trace.go:171","msg":"trace[379603885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1213; }","duration":"576.119398ms","start":"2026-09-22T02:01:06.99763Z","end":"2026-09-22T02:01:07.57375Z","steps":["trace[379603885] 'agreement among raft nodes before linearized reading' (duration: 576.077407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:07.573768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:06.997606Z","time spent":"576.159488ms","remote":"127.0.0.1:42926","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-09-22T02:01:07.57377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:06.997413Z","time spent":"576.348873ms","remote":"127.0.0.1:42926","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-09-22T02:01:07.573694Z","caller":"traceutil/trace.go:171","msg":"trace[1846473310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1213; }","duration":"573.156772ms","start":"2026-09-22T02:01:07.00053Z","end":"2026-09-22T02:01:07.573687Z","steps":["trace[1846473310] 'agreement among raft nodes before linearized reading' (duration: 573.08006ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:07.573876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:07.00052Z","time spent":"573.347916ms","remote":"127.0.0.1:42926","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-09-22T02:01:07.573965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.579903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-22T02:01:07.573987Z","caller":"traceutil/trace.go:171","msg":"trace[366829806] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1213; }","duration":"426.651405ms","start":"2026-09-22T02:01:07.14733Z","end":"2026-09-22T02:01:07.573982Z","steps":["trace[366829806] 'agreement among raft nodes before linearized reading' (duration: 426.597544ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:07.574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:07.147306Z","time spent":"426.690116ms","remote":"127.0.0.1:42926","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-09-22T02:01:07.574483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.937628ms","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-09-22T02:01:07.574545Z","caller":"traceutil/trace.go:171","msg":"trace[952830946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1213; }","duration":"577.013599ms","start":"2026-09-22T02:01:06.997515Z","end":"2026-09-22T02:01:07.574529Z","steps":["trace[952830946] 'agreement among raft nodes before linearized reading' (duration: 575.983806ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:07.574657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:06.99751Z","time spent":"577.131511ms","remote":"127.0.0.1:42926","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-09-22T02:01:08.265559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.236182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T02:01:08.265806Z","caller":"traceutil/trace.go:171","msg":"trace[971830325] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"686.650552ms","start":"2026-09-22T02:01:07.579131Z","end":"2026-09-22T02:01:08.265781Z","steps":["trace[971830325] 'process raft request' (duration: 366.092733ms)","trace[971830325] 'compare' (duration: 319.68326ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T02:01:08.265898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:07.579105Z","time spent":"686.754815ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:01:08.266105Z","caller":"traceutil/trace.go:171","msg":"trace[257633305] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"686.190284ms","start":"2026-09-22T02:01:07.579887Z","end":"2026-09-22T02:01:08.266077Z","steps":["trace[257633305] 'process raft request' (duration: 686.129971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:08.266258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:07.57986Z","time spent":"686.319897ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:01:08.266522Z","caller":"traceutil/trace.go:171","msg":"trace[1616864487] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"686.727085ms","start":"2026-09-22T02:01:07.579782Z","end":"2026-09-22T02:01:08.266509Z","steps":["trace[1616864487] 'process raft request' (duration: 686.127951ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:08.266759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:07.579772Z","time spent":"686.957071ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:01:08.26696Z","caller":"traceutil/trace.go:171","msg":"trace[336603824] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"687.699616ms","start":"2026-09-22T02:01:07.57924Z","end":"2026-09-22T02:01:08.26694Z","steps":["trace[336603824] 'process raft request' (duration: 686.423978ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:08.267056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:07.579232Z","time spent":"687.764717ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T02:01:14.117786Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14724977232523960209,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-22T02:01:14.618962Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14724977232523960209,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-22T02:01:14.945032Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.379142647s","expected-duration":"1s"} {"level":"info","ts":"2026-09-22T02:01:14.945426Z","caller":"traceutil/trace.go:171","msg":"trace[1963329920] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1309; }","duration":"1.327991315s","start":"2026-09-22T02:01:13.617416Z","end":"2026-09-22T02:01:14.945407Z","steps":["trace[1963329920] 'read index received' (duration: 1.327787501s)","trace[1963329920] 'applied index is now lower than readState.Index' (duration: 203.014µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T02:01:14.945504Z","caller":"traceutil/trace.go:171","msg":"trace[179408249] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"1.379727419s","start":"2026-09-22T02:01:13.565767Z","end":"2026-09-22T02:01:14.945494Z","steps":["trace[179408249] 'process raft request' (duration: 1.379497024s)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:14.945685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.747546ms","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-09-22T02:01:14.945721Z","caller":"traceutil/trace.go:171","msg":"trace[1551032793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1262; }","duration":"621.786207ms","start":"2026-09-22T02:01:14.323926Z","end":"2026-09-22T02:01:14.945712Z","steps":["trace[1551032793] 'agreement among raft nodes before linearized reading' (duration: 621.675814ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:14.945745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:14.323858Z","time spent":"621.880108ms","remote":"127.0.0.1:42926","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-09-22T02:01:14.945795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.638605ms","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-09-22T02:01:14.945861Z","caller":"traceutil/trace.go:171","msg":"trace[530514624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1262; }","duration":"622.735227ms","start":"2026-09-22T02:01:14.323109Z","end":"2026-09-22T02:01:14.945844Z","steps":["trace[530514624] 'agreement among raft nodes before linearized reading' (duration: 622.546543ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:14.945896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:14.323098Z","time spent":"622.789879ms","remote":"127.0.0.1:42926","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-09-22T02:01:14.94596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.47748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-22T02:01:14.945986Z","caller":"traceutil/trace.go:171","msg":"trace[1598660331] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1262; }","duration":"617.527961ms","start":"2026-09-22T02:01:14.32845Z","end":"2026-09-22T02:01:14.945978Z","steps":["trace[1598660331] 'agreement among raft nodes before linearized reading' (duration: 617.45123ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:14.946008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:14.328439Z","time spent":"617.563492ms","remote":"127.0.0.1:42926","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-09-22T02:01:14.946044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.970765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-22T02:01:14.946042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.32862057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-22T02:01:14.94609Z","caller":"traceutil/trace.go:171","msg":"trace[896024538] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1262; }","duration":"633.062108ms","start":"2026-09-22T02:01:14.313014Z","end":"2026-09-22T02:01:14.946076Z","steps":["trace[896024538] 'agreement among raft nodes before linearized reading' (duration: 632.992286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:14.946109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.225939ms","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-09-22T02:01:14.946119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:14.312994Z","time spent":"633.116869ms","remote":"127.0.0.1:43116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-09-22T02:01:14.946112Z","caller":"traceutil/trace.go:171","msg":"trace[1092547174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1262; }","duration":"1.328720872s","start":"2026-09-22T02:01:13.617373Z","end":"2026-09-22T02:01:14.946094Z","steps":["trace[1092547174] 'agreement among raft nodes before linearized reading' (duration: 1.328561139s)"],"step_count":1} {"level":"info","ts":"2026-09-22T02:01:14.946132Z","caller":"traceutil/trace.go:171","msg":"trace[1095473116] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1262; }","duration":"619.26477ms","start":"2026-09-22T02:01:14.32686Z","end":"2026-09-22T02:01:14.946125Z","steps":["trace[1095473116] 'agreement among raft nodes before linearized reading' (duration: 619.200398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:14.946154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:14.32685Z","time spent":"619.299202ms","remote":"127.0.0.1:42926","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-09-22T02:01:14.946151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:13.617354Z","time spent":"1.328788465s","remote":"127.0.0.1:42926","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-09-22T02:01:14.946482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.579723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-22T02:01:14.946551Z","caller":"traceutil/trace.go:171","msg":"trace[1914806288] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1262; }","duration":"479.465443ms","start":"2026-09-22T02:01:14.467041Z","end":"2026-09-22T02:01:14.946506Z","steps":["trace[1914806288] 'agreement among raft nodes before linearized reading' (duration: 478.533122ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:14.946546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144018103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-22T02:01:14.946579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:14.467022Z","time spent":"479.549484ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-09-22T02:01:14.946629Z","caller":"traceutil/trace.go:171","msg":"trace[12487880] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1262; }","duration":"1.145008575s","start":"2026-09-22T02:01:13.801568Z","end":"2026-09-22T02:01:14.946577Z","steps":["trace[12487880] 'agreement among raft nodes before linearized reading' (duration: 1.143997533s)"],"step_count":1} {"level":"warn","ts":"2026-09-22T02:01:14.946662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:13.801549Z","time spent":"1.145101887s","remote":"127.0.0.1:40394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-09-22T02:01:14.948261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T02:01:13.565747Z","time spent":"1.379779131s","remote":"127.0.0.1:42854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13709,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T02:05:42.455018Z","caller":"traceutil/trace.go:171","msg":"trace[1149520650] transaction","detail":"{read_only:false; response_revision:2522; number_of_response:1; }","duration":"117.645242ms","start":"2026-09-22T02:05:42.33735Z","end":"2026-09-22T02:05:42.454995Z","steps":["trace[1149520650] 'process raft request' (duration: 117.496698ms)"],"step_count":1}